Ticket #687: frontent-log.txt

File frontent-log.txt, 10.0 KB (added by torbjorn.jansson@…, 18 years ago)

frontend log from the crash (i had -v network on the frontend at the time of the crash)

Line 
12005-11-21 19:33:05.690 Using runtime prefix = /usr/local
22005-11-21 19:33:05.796 New DB connection, total: 1
32005-11-21 19:33:05.808 Total desktop dim: 720x576, with 1 screen[s].
42005-11-21 19:33:05.812 Using screen 0, 720x576 at 0,0
52005-11-21 19:33:05.817 Enabling Settings Cache.
62005-11-21 19:33:05.817 Clearing Settings Cache.
72005-11-21 19:33:05.819 mythfrontend version: 0.19.20051120-1 www.mythtv.org
82005-11-21 19:33:05.820 Enabled verbose msgs : important general network
92005-11-21 19:33:06.390 lang0: swe
102005-11-21 19:33:06.390 lang: swe
112005-11-21 19:33:06.442 lang1: eng
122005-11-21 19:33:06.442 lang: eng
132005-11-21 19:33:06.569 Total desktop dim: 720x576, with 1 screen[s].
142005-11-21 19:33:06.571 Using screen 0, 720x576 at 0,0
152005-11-21 19:33:06.576 Switching to square mode (MythCenter)
162005-11-21 19:33:07.839 Joystick disabled.
172005-11-21 19:33:07.841 Clearing Settings Cache for 'Language'.
182005-11-21 19:33:07.842 Clearing Settings Cache.
192005-11-21 19:33:07.842 New DB connection, total: 2
202005-11-21 19:33:07.915 Registering Internal as a media playback plugin.
212005-11-21 19:33:07.976 Registering MythDVD DVD Media Handler as a media handler
222005-11-21 19:33:07.978 Registering MythDVD VCD Media Handler as a media handler
232005-11-21 19:33:08.453 Registering MythMusic Media Handler as a media handler
242005-11-21 19:33:10.219 Starting media monitor.
252005-11-21 19:42:19.641 Connecting to backend server: 192.168.0.1:6543 (try 1 of 5)
262005-11-21 19:42:22.687 write -> 11 21      MYTH_PROTO_VERSION 22
272005-11-21 19:42:22.695 read  <- 11 13      ACCEPT[]:[]22
282005-11-21 19:42:22.695 Using protocol version 22
292005-11-21 19:42:22.695 write -> 11 20      ANN Monitor trogis 0
302005-11-21 19:42:22.762 read  <- 11 2       OK
312005-11-21 19:42:22.779 write -> 11 29      GET_NEXT_FREE_RECORDER[]:[]-1
322005-11-21 19:42:22.784 read  <- 11 26      1[]:[]192.168.0.1[]:[]6543
332005-11-21 19:42:22.790 TV: Attempting to change from None to WatchingLiveTV
342005-11-21 19:42:22.791 write -> 13 20      ANN Monitor trogis 1
352005-11-21 19:42:22.792 write -> 14 21      MYTH_PROTO_VERSION 22
362005-11-21 19:42:22.801 read  <- 14 13      ACCEPT[]:[]22
372005-11-21 19:42:22.801 Using protocol version 22
382005-11-21 19:42:22.802 write -> 14 21      ANN Playback trogis 0
392005-11-21 19:42:22.802 read  <- 13 2       OK
402005-11-21 19:42:22.809 read  <- 14 2       OK
412005-11-21 19:42:22.810 write -> 14 75      QUERY_RECORDER 1[]:[]SPAWN_LIVETV[]:[]live-trogis-2005-11-21T19:4...
422005-11-21 19:42:22.879 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
432005-11-21 19:42:22.922 read  <- 14 2       ok
442005-11-21 19:42:22.937 read  <- 13 81      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-trogis-2005-11-21T19...
452005-11-21 19:42:22.942 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
462005-11-21 19:42:22.947 read  <- 13 81      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-trogis-2005-11-21T19...
472005-11-21 19:42:22.971 New DB connection, total: 3
482005-11-21 19:42:22.979 Invalid file handle when opening /mnt/store//1004_20051121194223.nuv.  6 retries remaining.
492005-11-21 19:42:23.483 write -> 14 33      QUERY_RECORDER 1[]:[]IS_RECORDING
502005-11-21 19:42:23.488 read  <- 14 1       1
512005-11-21 19:42:23.488 write -> 14 33      QUERY_RECORDER 1[]:[]IS_RECORDING
522005-11-21 19:42:23.493 read  <- 14 1       1
532005-11-21 19:42:23.494 write -> 14 34      QUERY_RECORDER 1[]:[]GET_FRAMERATE
542005-11-21 19:42:23.509 read  <- 14 2       25
552005-11-21 19:42:23.802 write -> 11 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
562005-11-21 19:42:23.807 read  <- 11 2       OK
572005-11-21 19:42:23.818 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
582005-11-21 19:42:24.162 Opening audio device '/dev/dsp'.
592005-11-21 19:42:24.162 Opening OSS audio device '/dev/dsp'.
602005-11-21 19:42:24.362 write -> 14 39      QUERY_RECORDER 1[]:[]GET_FRAMES_WRITTEN
612005-11-21 19:42:24.367 read  <- 14 8       0[]:[]33
622005-11-21 19:42:24.367 write -> 14 50      QUERY_RECORDER 1[]:[]FILL_POSITION_MAP[]:[]1[]:[]1
632005-11-21 19:42:24.373 read  <- 14 24      0[]:[]1[]:[]0[]:[]761549
642005-11-21 19:42:33.574 TV: Changing from None to WatchingLiveTV
652005-11-21 19:42:33.728 write -> 14 35      QUERY_RECORDER 1[]:[]FRONTEND_READY
662005-11-21 19:42:33.739 read  <- 14 2       ok
672005-11-21 19:42:33.767 Using realtime priority.
682005-11-21 19:42:33.870 Video timing method: DRM
692005-11-21 19:43:05.032 write -> 11 28      GET_NEXT_FREE_RECORDER[]:[]1
702005-11-21 19:43:05.059 read  <- 11 26      2[]:[]192.168.0.1[]:[]6543
712005-11-21 19:43:05.082 TV: Attempting to change from WatchingLiveTV to None
722005-11-21 19:43:05.163 write -> 14 32      QUERY_RECORDER 1[]:[]STOP_LIVETV
732005-11-21 19:43:05.347 read  <- 13 49      BACKEND_MESSAGE[]:[]DONE_RECORDING 1 42[]:[]empty
742005-11-21 19:43:05.485 read  <- 14 2       ok
752005-11-21 19:43:05.496 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
762005-11-21 19:43:05.723 write -> 11 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
772005-11-21 19:43:05.728 read  <- 11 2       OK
782005-11-21 19:43:05.754 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
792005-11-21 19:43:05.766 TV: Changing from WatchingLiveTV to None
802005-11-21 19:43:05.769 TV: Attempting to change from None to WatchingLiveTV
812005-11-21 19:43:05.770 write -> 14 21      MYTH_PROTO_VERSION 22
822005-11-21 19:43:05.775 read  <- 14 13      ACCEPT[]:[]22
832005-11-21 19:43:05.775 Using protocol version 22
842005-11-21 19:43:05.775 write -> 14 21      ANN Playback trogis 0
852005-11-21 19:43:05.782 read  <- 14 2       OK
862005-11-21 19:43:05.783 write -> 14 75      QUERY_RECORDER 2[]:[]SPAWN_LIVETV[]:[]live-trogis-2005-11-21T19:4...
872005-11-21 19:43:05.843 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
882005-11-21 19:43:05.847 read  <- 14 2       ok
892005-11-21 19:43:05.855 Invalid file handle when opening /mnt/store//20347_20051121194305.mpg.  6 retries remaining.
902005-11-21 19:43:05.860 read  <- 13 81      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-trogis-2005-11-21T19...
912005-11-21 19:43:05.866 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
922005-11-21 19:43:05.931 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
932005-11-21 19:43:05.972 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
942005-11-21 19:43:06.025 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
952005-11-21 19:43:06.078 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
962005-11-21 19:43:06.131 read  <- 13 477     BACKEND_MESSAGE[]:[]SIGNAL 2[]:[]Signal Lock[]:[]slock 1 1 0 1 30...
972005-11-21 19:43:06.366 write -> 14 33      QUERY_RECORDER 2[]:[]IS_RECORDING
982005-11-21 19:43:06.371 read  <- 14 1       1
992005-11-21 19:43:06.371 write -> 14 33      QUERY_RECORDER 2[]:[]IS_RECORDING
1002005-11-21 19:43:06.376 read  <- 14 1       1
1012005-11-21 19:43:06.377 write -> 14 34      QUERY_RECORDER 2[]:[]GET_FRAMERATE
1022005-11-21 19:43:06.382 read  <- 14 2       -1
1032005-11-21 19:43:06.406 write -> 11 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
1042005-11-21 19:43:06.411 read  <- 11 2       OK
1052005-11-21 19:43:06.428 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
1062005-11-21 19:43:06.770 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
1072005-11-21 19:43:06.775 read  <- 13 81      BACKEND_MESSAGE[]:[]LIVETV_CHAIN UPDATE live-trogis-2005-11-21T19...
1080: start_time: 857.475 duration: -9223372036854.775
109stream: start_time: 9527.500 duration: -9223372036854.775 bitrate=0 kb/s
1102005-11-21 19:43:07.956 AFD: Opened codec 0xa2aa410, id(MPEG2VIDEO_XVMC_VLD) type(Video)
1112005-11-21 19:43:10.345 TV: Changing from None to WatchingLiveTV
1122005-11-21 19:43:10.345 write -> 14 35      QUERY_RECORDER 2[]:[]FRONTEND_READY
1132005-11-21 19:43:10.347 Using realtime priority.
1142005-11-21 19:43:10.351 read  <- 14 2       ok
1152005-11-21 19:43:10.370 Video timing method: DRM
1162005-11-21 19:43:11.471 Prebuffer wait timed out 10 times.
1172005-11-21 19:43:12.571 Prebuffer wait timed out 10 times.
1182005-11-21 19:43:13.081 NVP: prebuffering pause
1192005-11-21 19:43:15.082 NVP: prebuffering pause
1202005-11-21 19:43:15.105 write -> 11 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
1212005-11-21 19:43:15.110 read  <- 11 2       OK
1222005-11-21 19:43:15.125 read  <- 13 51      BACKEND_MESSAGE[]:[]RECORDING_LIST_CHANGE[]:[]empty
1232005-11-21 19:43:15.141 Invalid file handle when opening /mnt/store//20347_20051121194306.mpg.  4 retries remaining.
1242005-11-21 19:43:15.644 Invalid file handle when opening /mnt/store//20347_20051121194306.mpg.  3 retries remaining.
1252005-11-21 19:43:16.146 Invalid file handle when opening /mnt/store//20347_20051121194306.mpg.  2 retries remaining.
1262005-11-21 19:43:16.151 Prebuffer wait timed out 10 times.
1272005-11-21 19:43:16.648 Invalid file handle when opening /mnt/store//20347_20051121194306.mpg.  1 retries remaining.
1282005-11-21 19:43:17.154 Invalid file handle when opening /mnt/store//20347_20051121194306.mpg.  0 retries remaining.
1292005-11-21 19:43:17.251 Prebuffer wait timed out 10 times.
1302005-11-21 19:43:17.658 RingBuf(/mnt/store//20347_20051121194306.mpg) Error: File I/O problem in 'safe_read()'
131                        eno: t@V        P&L (9)
1322005-11-21 19:43:17.722 RingBuf(/mnt/store//20347_20051121194306.mpg) Error: File I/O problem in 'safe_read()'
133                        eno: ô/· (9)
1342005-11-21 19:43:17.784 RingBuf(/mnt/store//20347_20051121194306.mpg) Error: File I/O problem in 'safe_read()'
135                        eno: ô/· (9)
136mpegts_read_header: unable to read first 1024 bytes
1372005-11-21 19:43:17.786 AFD Error: Reset(): avformat err(-1) on av_open_input_file call.
1382005-11-21 19:43:17.793 RingBuf(/mnt/store//20347_20051121194306.mpg) Error: File I/O problem in 'safe_read()'
139                        eno: ô/· (9)
1402005-11-21 19:43:17.797 write -> 14 39      QUERY_RECORDER 2[]:[]GET_FRAMES_WRITTEN
1412005-11-21 19:43:17.804 read  <- 14 7       0[]:[]0
1422005-11-21 19:43:17.810 write -> 11 33      MESSAGE[]:[]RECORDING_LIST_CHANGE
1432005-11-21 19:43:17.816 read  <- 11 2       OK