2008-02-15 18:09:21.580 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:09:21.588 Empty LocalHostName. 2008-02-15 18:09:21.590 Using localhost value of corbin2 2008-02-15 18:09:21.645 New DB connection, total: 1 2008-02-15 18:09:21.658 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.662 Closing DB connection named 'DBManager0' 2008-02-15 18:09:21.665 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.671 Current Schema Version: 1208 Starting up as the master server. 2008-02-15 18:09:21.691 New DB connection, total: 2 2008-02-15 18:09:21.694 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.697 mythbackend: MythBackend started as master server 2008-02-15 18:09:21.800 TVRec(2): Start channel: 50752. 2008-02-15 18:09:21.803 DVBChan(2:3): Opening DVB channel 2008-02-15 18:09:21.805 DVBChan(2:3): Using DVB card 3, with frontend 'Genpix DVB-s'. 2008-02-15 18:09:21.810 New DB connection, total: 3 2008-02-15 18:09:21.812 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.817 New DB connection, total: 4 2008-02-15 18:09:21.819 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.824 New DB connection, total: 5 2008-02-15 18:09:21.826 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.830 New DB connection, total: 6 2008-02-15 18:09:21.833 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:21.851 DiSEqCDevTree: Changing LNB voltage to 18V 2008-02-15 18:09:22.355 DiSEqCDevTree: Resetting DiSEqC Bus 2008-02-15 18:09:22.357 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2008-02-15 18:09:23.174 ChannelBase(2): Input #11: 'DiSEqC Switch Input 1' schan(50752) sourceid(10) ccid(2) 2008-02-15 18:09:23.176 ChannelBase(2): Input #12: 'DiSEqC Switch Input 2' schan(50814) sourceid(11) ccid(2) 2008-02-15 18:09:23.177 ChannelBase(2): Current Input #11: 'DiSEqC Switch Input 1' 2008-02-15 18:09:23.179 DVBChan(2:3): SetChannelByString(50752): 2008-02-15 18:09:23.187 DVBChan(2:3): 12253160 qpsk a auto auto a a auto a r 2008-02-15 18:09:23.208 DiSEqCDevTree: Changing to DiSEqC switch port 1/2 2008-02-15 18:09:23.210 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0 2008-02-15 18:09:23.641 DVBChan(2:3): Old Params: 0 auto a auto auto a a auto a v DVBChan(2:3): New Params: 12253160 qpsk a auto auto a a auto a r 2008-02-15 18:09:23.643 DVBChan(2:3): Tune(): Tuning to 1003160kHz 2008-02-15 18:09:23.662 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:09:23.664 DVBChan(2:3): Tune(): Frequency tuning successful. 2008-02-15 18:09:23.665 DVBChan(2:3): SetChannelByString(50752): Tuned to frequency. 2008-02-15 18:09:23.697 TVRec(2): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:23.700 TVRec(2): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:23.716 TVRec(4): Start channel: 50752. 2008-02-15 18:09:23.720 DVBChan(4:4): Opening DVB channel 2008-02-15 18:09:23.722 DVBChan(4:4): Using DVB card 4, with frontend 'Genpix DVB-s'. 2008-02-15 18:09:23.729 DiSEqCDevTree: Changing LNB voltage to 18V 2008-02-15 18:09:24.232 DiSEqCDevTree: Resetting DiSEqC Bus 2008-02-15 18:09:24.235 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2008-02-15 18:09:25.050 ChannelBase(4): Input #111: 'DiSEqC Switch Input 1' schan(50752) sourceid(10) ccid(4) 2008-02-15 18:09:25.052 ChannelBase(4): Input #112: 'DiSEqC Switch Input 2' schan(50782) sourceid(11) ccid(4) 2008-02-15 18:09:25.054 ChannelBase(4): Current Input #111: 'DiSEqC Switch Input 1' 2008-02-15 18:09:25.055 DVBChan(4:4): SetChannelByString(50752): 2008-02-15 18:09:25.062 DVBChan(4:4): 12253160 qpsk a auto auto a a auto a r 2008-02-15 18:09:25.080 DiSEqCDevTree: Changing to DiSEqC switch port 1/2 2008-02-15 18:09:25.082 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0 2008-02-15 18:09:25.513 DVBChan(4:4): Old Params: 0 auto a auto auto a a auto a v DVBChan(4:4): New Params: 12253160 qpsk a auto auto a a auto a r 2008-02-15 18:09:25.515 DVBChan(4:4): Tune(): Tuning to 1003160kHz 2008-02-15 18:09:25.534 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:09:25.536 DVBChan(4:4): Tune(): Frequency tuning successful. 2008-02-15 18:09:25.537 DVBChan(4:4): SetChannelByString(50752): Tuned to frequency. 2008-02-15 18:09:25.539 TVRec(4): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:25.541 TVRec(4): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:25.545 TVRec(5): Start channel: 506. 2008-02-15 18:09:25.548 DVBChan(5:5): Opening DVB channel 2008-02-15 18:09:25.550 DVBChan(5:5): Using DVB card 5, with frontend 'Genpix DVB-s'. 2008-02-15 18:09:25.558 New DB connection, total: 7 2008-02-15 18:09:25.560 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:25.564 New DB connection, total: 8 2008-02-15 18:09:25.567 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:25.573 DiSEqCDevTree: Changing LNB voltage to 18V 2008-02-15 18:09:26.076 DiSEqCDevTree: Resetting DiSEqC Bus 2008-02-15 18:09:26.079 DiSEqCDevTree: Sending DiSEqC Command: e0 0 0 2008-02-15 18:09:28.138 ChannelBase(5): Input #3: 'DiSEqC v1.3 Input 1' schan(506) sourceid(2) ccid(5) 2008-02-15 18:09:28.141 ChannelBase(5): Input #4: 'DiSEqC v1.3 Input 2' schan(110) sourceid(3) ccid(5) 2008-02-15 18:09:28.142 ChannelBase(5): Input #5: 'DiSEqC v1.3 Input 3' schan(7867) sourceid(4) ccid(5) 2008-02-15 18:09:28.143 ChannelBase(5): Input #6: 'DiSEqC v1.3 Input 4' schan(7000) sourceid(5) ccid(5) 2008-02-15 18:09:28.144 ChannelBase(5): Input #7: 'DiSEqC v1.3 Input 5' schan(9484) sourceid(6) ccid(5) 2008-02-15 18:09:28.146 ChannelBase(5): Input #8: 'DiSEqC v1.3 Input 6' schan(22) sourceid(7) ccid(5) 2008-02-15 18:09:28.147 ChannelBase(5): Input #9: 'DiSEqC v1.3 Input 7' schan(5) sourceid(8) ccid(5) 2008-02-15 18:09:28.148 ChannelBase(5): Input #10: 'DiSEqC v1.3 Input 8' schan(3) sourceid(9) ccid(5) 2008-02-15 18:09:28.150 ChannelBase(5): Input #13: 'DiSEqC v1.3 Input 9' schan(9492) sourceid(12) ccid(5) 2008-02-15 18:09:28.151 ChannelBase(5): Input #14: 'DiSEqC v1.3 Input 10' schan(777) sourceid(13) ccid(5) 2008-02-15 18:09:28.153 ChannelBase(5): Input #17: 'DiSEqC v1.3 Input 13' schan(18) sourceid(14) ccid(5) 2008-02-15 18:09:28.154 ChannelBase(5): Input #18: 'DiSEqC v1.3 Input 14' schan(24) sourceid(15) ccid(5) 2008-02-15 18:09:28.155 ChannelBase(5): Input #19: 'DiSEqC v1.3 Input 15' schan(58) sourceid(16) ccid(5) 2008-02-15 18:09:28.157 ChannelBase(5): Input #20: 'DiSEqC v1.3 Input 16' schan() sourceid(17) ccid(5) 2008-02-15 18:09:28.158 ChannelBase(5): Input #21: 'DiSEqC v1.3 Input 17' schan(80263) sourceid(18) ccid(5) 2008-02-15 18:09:28.159 ChannelBase(5): Input #103: 'DiSEqC v1.3 Input 18' schan(7381) sourceid(102) ccid(5) 2008-02-15 18:09:28.160 ChannelBase(5): Input #104: 'DiSEqC v1.3 Input 19' schan(141) sourceid(103) ccid(5) 2008-02-15 18:09:28.162 ChannelBase(5): Current Input #3: 'DiSEqC v1.3 Input 1' 2008-02-15 18:09:28.163 DVBChan(5:5): SetChannelByString(506): 2008-02-15 18:09:28.172 DVBChan(5:5): 12326060 qpsk a auto auto a a auto a l 2008-02-15 18:09:28.194 DiSEqCDevTree: USALS Rotor - Goto -110 (Azimuth -36.0197) 2008-02-15 18:09:28.196 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 40 2008-02-15 18:09:28.396 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2008-02-15 18:09:28.398 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2008-02-15 18:09:28.825 DVBChan(5:5): Old Params: 0 auto a auto auto a a auto a v DVBChan(5:5): New Params: 12326060 qpsk a auto auto a a auto a l 2008-02-15 18:09:28.827 DVBChan(5:5): Tune(): Tuning to 1076060kHz 2008-02-15 18:09:28.843 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:09:28.845 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:09:28.846 DVBChan(5:5): SetChannelByString(506): Tuned to frequency. 2008-02-15 18:09:28.848 TVRec(5): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:28.851 TVRec(5): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:28.855 TVRec(105): Start channel: 458. 2008-02-15 18:09:28.857 DVBChan(105:5): Opening DVB channel 2008-02-15 18:09:28.858 DVBChan(5:5): Opening DVB channel 2008-02-15 18:09:30.753 ChannelBase(105): Input #1131: 'DiSEqC v1.3 Input 3' schan(807) sourceid(4) ccid(105) 2008-02-15 18:09:30.755 ChannelBase(105): Input #1132: 'DiSEqC v1.3 Input 6' schan(22) sourceid(7) ccid(105) 2008-02-15 18:09:30.756 ChannelBase(105): Input #1133: 'DiSEqC v1.3 Input 4' schan(7000) sourceid(5) ccid(105) 2008-02-15 18:09:30.758 ChannelBase(105): Input #1134: 'DiSEqC v1.3 Input 2' schan(496) sourceid(3) ccid(105) 2008-02-15 18:09:30.759 ChannelBase(105): Input #1135: 'DiSEqC v1.3 Input 1' schan(458) sourceid(2) ccid(105) 2008-02-15 18:09:30.760 ChannelBase(105): Input #1136: 'DiSEqC v1.3 Input 7' schan(20) sourceid(8) ccid(105) 2008-02-15 18:09:30.762 ChannelBase(105): Input #1137: 'DiSEqC v1.3 Input 8' schan(3) sourceid(9) ccid(105) 2008-02-15 18:09:30.763 ChannelBase(105): Input #1138: 'DiSEqC v1.3 Input 5' schan(9484) sourceid(6) ccid(105) 2008-02-15 18:09:30.764 ChannelBase(105): Input #1139: 'DiSEqC v1.3 Input 9' schan(363) sourceid(12) ccid(105) 2008-02-15 18:09:30.766 ChannelBase(105): Input #1140: 'DiSEqC v1.3 Input 18' schan(8110) sourceid(102) ccid(105) 2008-02-15 18:09:30.767 ChannelBase(105): Input #1141: 'DiSEqC v1.3 Input 19' schan(145) sourceid(103) ccid(105) 2008-02-15 18:09:30.769 ChannelBase(105): Input #1142: 'DiSEqC v1.3 Input 10' schan(709) sourceid(13) ccid(105) 2008-02-15 18:09:30.770 ChannelBase(105): Input #1143: 'DiSEqC v1.3 Input 13' schan(18) sourceid(14) ccid(105) 2008-02-15 18:09:30.771 ChannelBase(105): Input #1144: 'DiSEqC v1.3 Input 14' schan(24) sourceid(15) ccid(105) 2008-02-15 18:09:30.773 ChannelBase(105): Input #1145: 'DiSEqC v1.3 Input 15' schan(58) sourceid(16) ccid(105) 2008-02-15 18:09:30.774 ChannelBase(105): Input #1146: 'DiSEqC v1.3 Input 16' schan() sourceid(17) ccid(105) 2008-02-15 18:09:30.776 ChannelBase(105): Input #1147: 'DiSEqC v1.3 Input 17' schan(80504) sourceid(18) ccid(105) 2008-02-15 18:09:30.777 ChannelBase(105): Current Input #1131: 'DiSEqC v1.3 Input 3' 2008-02-15 18:09:30.779 DVBChan(105:5): SetChannelByString(458): 2008-02-15 18:09:30.782 ChannelBase(105): Failed to find channel(458) on current input (DiSEqC v1.3 Input 3) of card (105). 2008-02-15 18:09:30.785 ChannelBase(105): Found channel(458) on another input (DiSEqC v1.3 Input 1) of card (105). 2008-02-15 18:09:30.791 DVBChan(105:5) Warning: Your frequency setting (12413540) is out of range. (min/max:760000/2270000) 2008-02-15 18:09:30.793 DVBChan(105:5): 12413540 qpsk a auto auto a a auto a l 2008-02-15 18:09:30.794 DVBChan(105:5): tuning on slave channel 2008-02-15 18:09:30.796 DVBChan(5:5): Old Params: 12326060 qpsk a auto auto a a auto a l DVBChan(5:5): New Params: 12413540 qpsk a auto auto a a auto a l 2008-02-15 18:09:30.798 DVBChan(5:5): Tune(): Tuning to 1163540kHz 2008-02-15 18:09:30.814 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:09:30.816 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:09:30.817 DVBChan(105:5): SetChannelByString(458): Tuned to frequency. 2008-02-15 18:09:30.819 TVRec(105): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:30.821 TVRec(105): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:30.825 TVRec(205): Start channel: 122. 2008-02-15 18:09:30.827 DVBChan(205:5): Opening DVB channel 2008-02-15 18:09:30.828 DVBChan(5:5): Opening DVB channel 2008-02-15 18:09:31.713 mythbackend: Running housekeeping thread 2008-02-15 18:09:32.725 ChannelBase(205): Input #1148: 'DiSEqC v1.3 Input 3' schan(807) sourceid(4) ccid(205) 2008-02-15 18:09:32.727 ChannelBase(205): Input #1149: 'DiSEqC v1.3 Input 6' schan(22) sourceid(7) ccid(205) 2008-02-15 18:09:32.729 ChannelBase(205): Input #1150: 'DiSEqC v1.3 Input 4' schan(7000) sourceid(5) ccid(205) 2008-02-15 18:09:32.730 ChannelBase(205): Input #1151: 'DiSEqC v1.3 Input 2' schan(122) sourceid(3) ccid(205) 2008-02-15 18:09:32.731 ChannelBase(205): Input #1152: 'DiSEqC v1.3 Input 1' schan(507) sourceid(2) ccid(205) 2008-02-15 18:09:32.733 ChannelBase(205): Input #1153: 'DiSEqC v1.3 Input 7' schan(20) sourceid(8) ccid(205) 2008-02-15 18:09:32.734 ChannelBase(205): Input #1154: 'DiSEqC v1.3 Input 8' schan(3) sourceid(9) ccid(205) 2008-02-15 18:09:32.735 ChannelBase(205): Input #1155: 'DiSEqC v1.3 Input 5' schan(9487) sourceid(6) ccid(205) 2008-02-15 18:09:32.737 ChannelBase(205): Input #1156: 'DiSEqC v1.3 Input 9' schan(363) sourceid(12) ccid(205) 2008-02-15 18:09:32.738 ChannelBase(205): Input #1157: 'DiSEqC v1.3 Input 18' schan(8110) sourceid(102) ccid(205) 2008-02-15 18:09:32.739 ChannelBase(205): Input #1158: 'DiSEqC v1.3 Input 19' schan(145) sourceid(103) ccid(205) 2008-02-15 18:09:32.741 ChannelBase(205): Input #1159: 'DiSEqC v1.3 Input 10' schan(709) sourceid(13) ccid(205) 2008-02-15 18:09:32.742 ChannelBase(205): Input #1160: 'DiSEqC v1.3 Input 13' schan(18) sourceid(14) ccid(205) 2008-02-15 18:09:32.743 ChannelBase(205): Input #1161: 'DiSEqC v1.3 Input 14' schan(24) sourceid(15) ccid(205) 2008-02-15 18:09:32.745 ChannelBase(205): Input #1162: 'DiSEqC v1.3 Input 15' schan(58) sourceid(16) ccid(205) 2008-02-15 18:09:32.746 ChannelBase(205): Input #1163: 'DiSEqC v1.3 Input 16' schan() sourceid(17) ccid(205) 2008-02-15 18:09:32.748 ChannelBase(205): Input #1164: 'DiSEqC v1.3 Input 17' schan(80504) sourceid(18) ccid(205) 2008-02-15 18:09:32.749 ChannelBase(205): Current Input #1148: 'DiSEqC v1.3 Input 3' 2008-02-15 18:09:32.750 DVBChan(205:5): SetChannelByString(122): 2008-02-15 18:09:32.754 ChannelBase(205): Failed to find channel(122) on current input (DiSEqC v1.3 Input 3) of card (205). 2008-02-15 18:09:32.756 ChannelBase(205): Found channel(122) on another input (DiSEqC v1.3 Input 2) of card (205). 2008-02-15 18:09:32.763 DVBChan(205:5) Warning: Your frequency setting (12326060) is out of range. (min/max:760000/2270000) 2008-02-15 18:09:32.764 DVBChan(205:5): 12326060 qpsk a auto auto a a auto a l 2008-02-15 18:09:32.765 DVBChan(205:5): tuning on slave channel 2008-02-15 18:09:32.785 DiSEqCDevTree: USALS Rotor - Goto -119 (Azimuth -45.8453) 2008-02-15 18:09:32.787 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 dd 2008-02-15 18:09:32.987 DVBChan(5:5): Old Params: 12413540 qpsk a auto auto a a auto a l DVBChan(5:5): New Params: 12326060 qpsk a auto auto a a auto a l 2008-02-15 18:09:32.989 DVBChan(5:5): Tune(): Tuning to 1076060kHz 2008-02-15 18:09:33.005 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:09:33.007 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:09:33.008 DVBChan(205:5): SetChannelByString(122): Tuned to frequency. 2008-02-15 18:09:33.010 TVRec(205): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:33.012 TVRec(205): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:33.015 TVRec(1000): Start channel: 504. 2008-02-15 18:09:33.017 Channel(/dev/video0): Device name 'WinTV PVR 500 (unit #1)' driver 'ivtv'. 2008-02-15 18:09:34.018 ChannelBase(1000): Input #1: 'S-Video 1' schan(504) sourceid(1) ccid(1000) 2008-02-15 18:09:34.020 ChannelBase(1000): Current Input #1: 'S-Video 1' 2008-02-15 18:09:34.026 Global TVFormat Setting 'NTSC' 2008-02-15 18:09:34.028 Channel(/dev/video0): Input #1: 'S-Video 1' schan(504) tun() v4l1(NTSC) v4l2(NTSC) 2008-02-15 18:09:34.029 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) 2008-02-15 18:09:34.030 Channel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) 2008-02-15 18:09:34.032 Channel(/dev/video0): SetFormat(NTSC) fmt(NTSC) input(1) 2008-02-15 18:09:34.036 Channel(/dev/video0)::SwitchToInput(in 1, '') 2008-02-15 18:09:34.038 Channel(/dev/video0): SetInputAndFormat(1, NTSC) (v4l v2) 2008-02-15 18:09:34.040 Channel(/dev/video0): SetChannelByString(504) 2008-02-15 18:09:34.044 Channel(/dev/video0): SetFormat(Default) fmt(NTSC) input(1) 2008-02-15 18:09:34.046 External channel change: /usr/local/bin/RCA.pl 504 2008-02-15 18:09:34.051 Waiting for External Tuning program to exit 2008-02-15 18:09:35.054 ret_pid(5184) child(5184) status(0x0) 2008-02-15 18:09:35.058 External Tuning program no longer running 2008-02-15 18:09:35.059 External Tuning program exited with no error 2008-02-15 18:09:35.062 Channel(/dev/video0): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-15 18:09:35.067 Channel(/dev/video0): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:09:35.071 Channel(/dev/video0): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:09:35.077 Channel(/dev/video0): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-15 18:09:35.080 TVRec(1000): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:35.082 TVRec(1000): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:35.085 TVRec(1001): Start channel: 504. 2008-02-15 18:09:35.088 Channel(/dev/video1): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. 2008-02-15 18:09:36.091 ChannelBase(1001): Input #2: 'S-Video 1' schan(504) sourceid(1) ccid(1001) 2008-02-15 18:09:36.093 ChannelBase(1001): Current Input #2: 'S-Video 1' 2008-02-15 18:09:36.094 Global TVFormat Setting 'NTSC' 2008-02-15 18:09:36.096 Channel(/dev/video1): Input #2: 'S-Video 1' schan(504) tun() v4l1(NTSC) v4l2(NTSC) 2008-02-15 18:09:36.097 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-15 18:09:36.099 Channel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) 2008-02-15 18:09:36.100 Channel(/dev/video1): SetFormat(NTSC) fmt(NTSC) input(2) 2008-02-15 18:09:36.101 Channel(/dev/video1)::SwitchToInput(in 2, '') 2008-02-15 18:09:36.105 Channel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) 2008-02-15 18:09:36.106 Channel(/dev/video1): SetChannelByString(504) 2008-02-15 18:09:36.110 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-15 18:09:36.112 External channel change: /usr/local/bin/RCA2.pl 504 2008-02-15 18:09:36.115 Waiting for External Tuning program to exit 5042008-02-15 18:09:37.119 ret_pid(5186) child(5186) status(0x0) 2008-02-15 18:09:37.123 External Tuning program no longer running 2008-02-15 18:09:37.124 External Tuning program exited with no error 2008-02-15 18:09:37.128 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-15 18:09:37.132 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:09:37.136 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:09:37.142 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-15 18:09:37.145 TVRec(1001): SetFlags(RunMainLoop,) -> RunMainLoop, 2008-02-15 18:09:37.148 TVRec(1001): ClearFlags(ExitPlayer,FinishRecording,) -> RunMainLoop, 2008-02-15 18:09:37.157 New DB scheduler connection 2008-02-15 18:09:37.161 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:09:38.559 Main::Registering HttpStatus Extension 2008-02-15 18:09:38.561 mythbackend version: 0.21.20080114-1 www.mythtv.org 2008-02-15 18:09:38.563 Enabled verbose msgs: important general channel record 2008-02-15 18:09:38.572 AutoExpire: CalcParams(): Max required Free Space: 5.0 GB w/freq: 15 min 2008-02-15 18:09:39.143 MainServer::HandleAnnounce Monitor 2008-02-15 18:09:39.145 adding: pc4 as a client (events: 0) 2008-02-15 18:09:39.147 MainServer::HandleAnnounce Monitor 2008-02-15 18:09:39.149 adding: pc4 as a client (events: 1) 2008-02-15 18:09:40.204 Reschedule requested for id -1. 2008-02-15 18:09:44.955 MainServer::HandleAnnounce Monitor 2008-02-15 18:09:44.963 adding: pc4 as a client (events: 0) 2008-02-15 18:09:44.966 MainServer::HandleAnnounce Monitor 2008-02-15 18:09:44.967 adding: pc4 as a client (events: 1) 2008-02-15 18:09:45.994 Scheduled 247 items in 5.8 = 0.15 match + 5.63 place 2008-02-15 18:09:46.007 scheduler: Scheduled items: Scheduled 247 items in 5.8 = 0.15 match + 5.63 place 2008-02-15 18:09:46.018 Seem to be woken up by USER 2008-02-15 18:09:47.534 UPnpMedia: BuildMediaMap VIDEO scan starting in :/shares/Videos: 2008-02-15 18:09:50.460 UPnpMedia: BuildMediaMap Done. Found 1370 objects 2008-02-15 18:10:57.202 AutoExpire: CalcParams(): Max required Free Space: 5.0 GB w/freq: 15 min 2008-02-15 18:11:54.304 Getting next free recorder after : -1 2008-02-15 18:11:54.310 Checking card 1. Best card so far -1 2008-02-15 18:11:54.312 Checking card 2. Best card so far 2 2008-02-15 18:11:54.313 Checking card 3. Best card so far 2 2008-02-15 18:11:54.314 Checking card 4. Best card so far 4 2008-02-15 18:11:54.316 Checking card 5. Best card so far 5 2008-02-15 18:11:54.317 Checking card 12. Best card so far 5 2008-02-15 18:11:54.318 Checking card 105. Best card so far 105 2008-02-15 18:11:54.320 Checking card 205. Best card so far 205 2008-02-15 18:11:54.321 Checking card 1000. Best card so far 1000 2008-02-15 18:11:54.322 Checking card 1001. Best card so far 1001 2008-02-15 18:11:54.326 MainServer::HandleAnnounce Playback 2008-02-15 18:11:54.327 adding: pc4 as a client (events: 0) 2008-02-15 18:11:54.332 TVRec(1001): Changing from None to WatchingLiveTV 2008-02-15 18:11:54.335 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2008-02-15 18:11:54.337 TVRec(1001): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-15 18:11:54.340 TVRec(1001): Start channel: 504. 2008-02-15 18:11:54.349 TVRec(1001): HW Tuner: 1001->1001 2008-02-15 18:11:54.350 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2008-02-15 18:11:54.361 TVRec(1001): No recorder yet, calling TuningFrequency 2008-02-15 18:11:54.364 Channel(/dev/video1): Device name 'WinTV PVR 500 (unit #2)' driver 'ivtv'. 2008-02-15 18:11:55.372 ChannelBase(1001): Input #2: 'S-Video 1' schan(504) sourceid(1) ccid(1001) 2008-02-15 18:11:55.374 ChannelBase(1001): Current Input #2: 'S-Video 1' 2008-02-15 18:11:55.375 Global TVFormat Setting 'NTSC' 2008-02-15 18:11:55.377 Channel(/dev/video1): Input #2: 'S-Video 1' schan(504) tun() v4l1(NTSC) v4l2(NTSC) 2008-02-15 18:11:55.378 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-15 18:11:55.380 Channel(/dev/video1)::SwitchToInput(in 2, '') 2008-02-15 18:11:55.388 Channel(/dev/video1): SetInputAndFormat(2, NTSC) (v4l v2) 2008-02-15 18:11:55.390 Channel(/dev/video1): SetChannelByString(504) 2008-02-15 18:11:55.400 Channel(/dev/video1): SetFormat(Default) fmt(NTSC) input(2) 2008-02-15 18:11:55.401 External channel change: /usr/local/bin/RCA2.pl 504 2008-02-15 18:11:55.405 Waiting for External Tuning program to exit 5042008-02-15 18:11:56.409 ret_pid(5400) child(5400) status(0x0) 2008-02-15 18:11:56.413 External Tuning program no longer running 2008-02-15 18:11:56.414 External Tuning program exited with no error 2008-02-15 18:11:56.420 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-15 18:11:56.425 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:11:56.431 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:11:56.438 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-15 18:11:56.441 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop, 2008-02-15 18:11:56.442 TVRec(1001): SetFlags(NeedToStartRecorder,) -> RunMainLoop,NeedToStartRecorder, 2008-02-15 18:11:56.443 TVRec(1001): Starting Recorder 2008-02-15 18:11:56.510 Using profile 'Live TV' to record 2008-02-15 18:11:56.512 TVRec(1001): CreateLiveTVRingBuffer() 2008-02-15 18:11:56.513 TVRec(1001): GetProgramRingBufferForLiveTV() 2008-02-15 18:11:57.641 TVRec(1001): StartedRecording(0x885c4c0) fn(/shares/recordings/LiveTV/1504_20080215181156.mpg) 2008-02-15 18:11:57.652 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:11:57.660 Chain: Appended@0 '1504_20080215181156' 2008-02-15 18:11:57.666 TVRec(1001): SetFlags(RingBufferReady,) -> RunMainLoop,NeedToStartRecorder,RingBufferReady, 2008-02-15 18:11:57.680 RecBase(1001:/dev/video1): SetRingBuffer(0x87e9228) '/shares/recordings/LiveTV/1504_20080215181156.mpg' 2008-02-15 18:11:57.681 RecBase(1001:/dev/video1): SetRecording(0x88f5bb0) title(The Princess Bride) 2008-02-15 18:11:57.686 Channel(/dev/video1): InitPictureAttribute(brightness): 128 [ 0, 255] dflt( 128, 0.50, 128) 2008-02-15 18:11:57.691 Channel(/dev/video1): InitPictureAttribute( contrast): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:11:57.696 Channel(/dev/video1): InitPictureAttribute( colour): 63 [ 0, 127] dflt( 64, 0.50, 257) 2008-02-15 18:11:57.702 Channel(/dev/video1): InitPictureAttribute( hue): 0 [ -128, 127] dflt( 0, 0.50, 128) 2008-02-15 18:11:57.705 TVRec(1001): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2008-02-15 18:11:57.705 MPEGRec(/dev/video1): usingv4l2(1) has_v4l2_vbi(1) has_buggy_vbi(1) 2008-02-15 18:11:57.736 MPEGRec(/dev/video1) Warning: Stream type 'MPEG-2 TS' is not supported by ivtv driver, using 'MPEG-2 PS' instead. 2008-02-15 18:11:57.738 AutoExpire: CalcParams(): Max required Free Space: 6.0 GB w/freq: 15 min 2008-02-15 18:11:57.877 MPEGRec(/dev/video1) Warning: VBI recording with broken drivers. Upgrade to ivtv 0.10.0 if you experience problems. 2008-02-15 18:11:57.967 MPEGRec(/dev/video1): VBI service: 4096, io size: 0 2008-02-15 18:11:57.992 TVRec(1001): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:11:57.995 TVRec(1001): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:12:00.595 TVRec(1001): SetFlags(FrontendReady,) -> FrontendReady,RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:12:39.926 TVRec(1001): Found channel (9494) on different card(5). 2008-02-15 18:12:40.032 TVRec(1001): StopLiveTV(void) curRec: 0x885c4c0 pseudoRec: 0 2008-02-15 18:12:40.040 TVRec(1001): Changing from WatchingLiveTV to None 2008-02-15 18:12:40.043 TVRec(1001): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop,RecorderRunning,RingBufferReady, 2008-02-15 18:12:40.044 TVRec(1001): Request: Program(no) channel() input() flags(KillRec,KillRingBuffer,) 2008-02-15 18:12:40.165 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-15 18:12:40.195 TVRec(1001): FinishedRecording(The Princess Bride) in recgroup: LiveTV 2008-02-15 18:12:40.199 Chain: Updated endtime for '1504_20080215181156' to 20080215181240 2008-02-15 18:12:40.204 Finished recording The Princess Bride: channel 1504 2008-02-15 18:12:40.214 scheduler: Finished recording: The Princess Bride: channel 1504 2008-02-15 18:12:40.219 TVRec(1001): ClearFlags(RecorderRunning,) -> RunMainLoop,RingBufferReady, 2008-02-15 18:12:40.220 TVRec(1001): Tearing down RingBuffer 2008-02-15 18:12:40.223 TVRec(1001): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,RingBufferReady, 2008-02-15 18:12:40.463 MainServer::HandleAnnounce Playback 2008-02-15 18:12:40.465 adding: pc4 as a client (events: 0) 2008-02-15 18:12:40.470 TVRec(5): Changing from None to WatchingLiveTV 2008-02-15 18:12:40.472 TVRec(5): ClearFlags(FrontendReady,CancelNextRecording,) -> RunMainLoop, 2008-02-15 18:12:40.473 TVRec(5): Request: Program(no) channel() input() flags(LiveTV,) 2008-02-15 18:12:40.474 ChannelBase(5): Looking for startchannel '9494' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:12:40.506 ChannelBase(5): Found startchannel '9494' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:12:40.509 TVRec(5): HW Tuner: 5->5 2008-02-15 18:12:40.510 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop, 2008-02-15 18:12:40.511 TVRec(5): No recorder yet, calling TuningFrequency 2008-02-15 18:12:40.516 DVBChan(5:5): Opening DVB channel 2008-02-15 18:12:40.517 DVBChan(5:5): SetChannelByString(9494): 2008-02-15 18:12:40.521 ChannelBase(5): Failed to find channel(9494) on current input (DiSEqC v1.3 Input 1) of card (5). 2008-02-15 18:12:40.526 ChannelBase(5): Found channel(9494) on another input (DiSEqC v1.3 Input 5) of card (5). 2008-02-15 18:12:40.539 DVBChan(5:5): 12369800 t_8psk a auto auto a a auto a r 2008-02-15 18:12:40.542 DiSEqCDevTree: Overriding voltage to 18V for faster rotor movement 2008-02-15 18:12:40.562 DiSEqCDevTree: USALS Rotor - Goto -129 (Azimuth -56.5947) 2008-02-15 18:12:40.563 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d3 89 2008-02-15 18:12:40.763 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2008-02-15 18:12:40.765 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0 2008-02-15 18:12:41.197 DVBChan(5:5): Old Params: 12326060 qpsk a auto auto a a auto a l DVBChan(5:5): New Params: 12369800 t_8psk a auto auto a a auto a r 2008-02-15 18:12:41.200 DVBChan(5:5): Tune(): Tuning to 1119800kHz 2008-02-15 18:12:41.217 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:12:41.219 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:12:41.220 DVBChan(5:5): SetChannelByString(9494): Tuned to frequency. 2008-02-15 18:12:41.221 TVRec(5): CreateLiveTVRingBuffer() 2008-02-15 18:12:41.223 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:12:42.286 TVRec(5): StartedRecording(0x8550048) fn(/shares/recordings/LiveTV/486_20080215181241.mpg) 2008-02-15 18:12:42.295 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:12:42.304 Chain: Appended@1 '486_20080215181241' 2008-02-15 18:12:42.310 TVRec(5): Starting Signal Monitor 2008-02-15 18:12:42.312 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:12:42.313 DVBChan(5:5): Opening DVB channel 2008-02-15 18:12:42.328 DVBSM(5): Can measure Signal Strength 2008-02-15 18:12:42.329 DVBSM(5): Can measure S/N 2008-02-15 18:12:42.330 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:12:42.332 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:12:42.333 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:12:42.334 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:12:42.336 TVRec(5): Signal monitor successfully created 2008-02-15 18:12:42.337 TVRec(5): Setting up table monitoring. 2008-02-15 18:12:42.349 Using profile 'Live TV' to record 2008-02-15 18:12:42.351 TVRec(5): DVB service_id 9494 on net_id 4104 tsid 411 2008-02-15 18:12:42.353 DTVSM(5)::SetDVBService(transport_id: 411, network_id: 4104, service_id: 9494): 2008-02-15 18:12:42.355 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:12:42.356 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:12:42.357 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:12:42.359 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:12:42.360 SM(5)::Start: begin 2008-02-15 18:12:42.362 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:12:42.363 SM(5)::Start: end 2008-02-15 18:12:42.366 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,SignalMonitorRunning, 2008-02-15 18:12:42.367 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,SignalMonitorRunning, 2008-02-15 18:12:42.369 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning, 2008-02-15 18:12:42.370 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning, 2008-02-15 18:12:42.371 DummyDTVRecorder -- started 2008-02-15 18:12:42.373 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:42.374 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:42.375 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:42.376 TVRec(5): SetFlags(CancelNextRecording,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:42.390 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:12:46.017 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:12:46.553 DVBSH(5): Retuning for rotor completion 2008-02-15 18:12:46.555 DiSEqCDevTree: Changing LNB voltage to 13V 2008-02-15 18:12:46.558 DVBChan(5:5): Old Params: 12369800 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12369800 t_8psk a auto auto a a auto a r 2008-02-15 18:12:46.560 DVBChan(5:5): Tune(): Tuning to 1119800kHz 2008-02-15 18:12:46.576 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:12:46.578 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:12:46.593 DVBSM(5): UpdateValues -- Signal Lost 2008-02-15 18:12:46.712 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:12:46.713 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:12:46.715 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:12:46.717 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:12:46.717 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:12:46.721 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:12:46.723 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:12:46.724 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:12:46.734 DVBSH(5): RunTS(): begin 2008-02-15 18:12:46.737 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:12:46.738 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:12:46.740 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:12:46.741 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:12:46.743 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:12:46.744 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:12:46.746 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:12:46.748 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:12:46.819 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:12:46.820 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:12:46.822 CreatePATSingleProgram() 2008-02-15 18:12:46.823 PAT in input stream 2008-02-15 18:12:46.825 Program Association Table PSIP tableID(0x0) length(41) extension(0x19b) version(8) current(1) section(0) last_section(0) tsid: 411 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 5455 has PID 0x 21 data 0x15 0x4f 0xe0 0x21 program number 5456 has PID 0x 21 data 0x15 0x50 0xe0 0x21 program number 9463 has PID 0x 21 data 0x24 0xf7 0xe0 0x21 program number 9483 has PID 0x 21 data 0x25 0x b 0xe0 0x21 program number 9484 has PID 0x 21 data 0x25 0x c 0xe0 0x21 program number 9492 has PID 0x 21 data 0x25 0x14 0xe0 0x21 program number 9494 has PID 0x 21 data 0x25 0x16 0xe0 0x21 2008-02-15 18:12:46.827 desired_program(9494) pid(0x21) 2008-02-15 18:12:46.828 pmt_pid(0x21) 2008-02-15 18:12:46.830 PAT for output stream 2008-02-15 18:12:46.831 Program Association Table PSIP tableID(0x0) length(13) extension(0x19b) version(8) current(1) section(0) last_section(0) tsid: 411 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:12:46.833 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:12:46.834 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:12:46.849 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.851 DTVSM(5) Error: Wrong PMT; pmt->pn(5455) desired(9494) 2008-02-15 18:12:46.853 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.854 DTVSM(5) Error: Wrong PMT; pmt->pn(5456) desired(9494) 2008-02-15 18:12:46.871 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.872 DTVSM(5) Error: Wrong PMT; pmt->pn(9492) desired(9494) 2008-02-15 18:12:46.874 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.875 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-15 18:12:46.878 DTVSM(5) Error: Wrong PMT; pmt->pn(9463) desired(9494) 2008-02-15 18:12:46.881 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.882 DTVSM(5) Error: Wrong PMT; pmt->pn(9483) desired(9494) 2008-02-15 18:12:46.895 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.897 DTVSM(5) Error: Wrong PMT; pmt->pn(9484) desired(9494) 2008-02-15 18:12:46.898 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:12:46.900 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:12:46.902 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:12:46.903 CreatePMTSingleProgram() 2008-02-15 18:12:46.904 PMT in input stream 2008-02-15 18:12:46.906 Program Map Table ver(0) pid(0x21) pnum(9494) len(65) Stream #0 pid(0x1222) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) Stream #1 pid(0x1223) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:12:46.908 PMT for output stream 2008-02-15 18:12:46.910 Program Map Table ver(0) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1222) type(video-h264 0x1b) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:12:46.912 DVBSH(5): AddPIDFilter(0x1222) priority 3 2008-02-15 18:12:46.913 PIDInfo(5): Opening filter for pid 0x1222 2008-02-15 18:12:46.915 DVBSH(5): AddPIDFilter(0x1223) priority 2 2008-02-15 18:12:46.917 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:12:46.930 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:12:47.449 PID 0x1222 status: Decrypted 2008-02-15 18:12:47.505 PID 0x1223 status: Decrypted 2008-02-15 18:12:47.507 Program 9494 status: Decrypted 2008-02-15 18:12:47.508 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:12:47.510 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:12:48.346 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:12:48.348 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:12:48.350 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:12:48.351 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:12:48.394 TVRec(5): Got good signal 2008-02-15 18:12:48.396 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:12:48.398 DVBSM(5): Stop() -- begin 2008-02-15 18:12:48.399 SM(5)::Stop: begin 2008-02-15 18:12:48.452 SM(5)::Stop: end 2008-02-15 18:12:48.453 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:12:48.455 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:12:48.456 DVBSH(5): RunTS(): shutdown 2008-02-15 18:12:48.458 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:12:48.460 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:12:48.462 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:12:48.463 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:12:48.465 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:12:48.467 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:12:48.468 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:12:48.470 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:12:48.472 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:12:48.473 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:12:48.475 DVBSH(5): RemovePIDFilter(0x1222) 2008-02-15 18:12:48.476 PIDInfo(5): Closing filter for pid 0x1222 2008-02-15 18:12:48.478 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:12:48.480 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:12:48.483 DVBSH(5): RunTS(): end 2008-02-15 18:12:48.485 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:12:48.487 DVBSM(5): Stop() -- end 2008-02-15 18:12:48.489 DVBSM(5): Stop() -- begin 2008-02-15 18:12:48.490 SM(5)::Stop: begin 2008-02-15 18:12:48.491 SM(5)::Stop: end 2008-02-15 18:12:48.493 DVBSM(5): Stop() -- end 2008-02-15 18:12:48.494 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:12:48.494 DVBSM(5): Stop() -- begin 2008-02-15 18:12:48.496 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:48.498 SM(5)::Stop: begin 2008-02-15 18:12:48.500 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,DummyRecorderRunning,RingBufferReady, 2008-02-15 18:12:48.502 SM(5)::Stop: end 2008-02-15 18:12:48.506 DVBSM(5): Stop() -- end 2008-02-15 18:12:48.508 SM(5)::Stop: begin 2008-02-15 18:12:48.508 TVRec(5): Starting Recorder 2008-02-15 18:12:48.510 SM(5)::Stop: end 2008-02-15 18:12:48.511 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RingBufferReady, 2008-02-15 18:12:48.521 TVRec(5): FinishedRecording(Unknown) in recgroup: LiveTV 2008-02-15 18:12:48.524 Chain: Updated endtime for '486_20080215181241' to 20080215181248 2008-02-15 18:12:48.529 Finished recording Unknown: channel 486 2008-02-15 18:12:48.541 scheduler: Finished recording: Unknown: channel 486 2008-02-15 18:12:48.556 Using profile 'Live TV' to record 2008-02-15 18:12:48.558 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:12:48.559 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:12:49.619 TVRec(5): StartedRecording(0xb122f860) fn(/shares/recordings/LiveTV/486_20080215181248.mpg) 2008-02-15 18:12:49.629 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:12:49.646 TVRec(5): FinishedRecording(Unknown) in recgroup: LiveTV 2008-02-15 18:12:49.650 Chain: Updated endtime for '486_20080215181241' to 20080215181249 2008-02-15 18:12:49.684 Finished recording Unknown: channel 486 2008-02-15 18:12:49.691 Preview: 'myth://192.168.1.121:6543/486_20080215181241.mpg' is not local, replacing with '/shares/recordings/LiveTV/486_20080215181241.mpg', which is local. 2008-02-15 18:12:49.706 Chain: Appended@2 '486_20080215181248' 2008-02-15 18:12:49.734 RecBase(5:5): SetRingBuffer(0xb123bca8) '/shares/recordings/LiveTV/486_20080215181248.mpg' 2008-02-15 18:12:49.739 DVBRec(5:5): SetPAT(9494 on 0x21) 2008-02-15 18:12:49.741 CreatePATSingleProgram() 2008-02-15 18:12:49.742 PAT in input stream 2008-02-15 18:12:49.744 Program Association Table PSIP tableID(0x0) length(41) extension(0x19b) version(8) current(1) section(0) last_section(0) tsid: 411 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 5455 has PID 0x 21 data 0x15 0x4f 0xe0 0x21 program number 5456 has PID 0x 21 data 0x15 0x50 0xe0 0x21 program number 9463 has PID 0x 21 data 0x24 0xf7 0xe0 0x21 program number 9483 has PID 0x 21 data 0x25 0x b 0xe0 0x21 program number 9484 has PID 0x 21 data 0x25 0x c 0xe0 0x21 program number 9492 has PID 0x 21 data 0x25 0x14 0xe0 0x21 program number 9494 has PID 0x 21 data 0x25 0x16 0xe0 0x21 2008-02-15 18:12:49.746 desired_program(9494) pid(0x21) 2008-02-15 18:12:49.748 pmt_pid(0x21) 2008-02-15 18:12:49.749 PAT for output stream 2008-02-15 18:12:49.750 Program Association Table PSIP tableID(0x0) length(13) extension(0x19b) version(8) current(1) section(0) last_section(0) tsid: 411 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:12:49.753 DVBRec(5:5): SetPMT(9494) 2008-02-15 18:12:49.754 CreatePMTSingleProgram() 2008-02-15 18:12:49.756 PMT in input stream 2008-02-15 18:12:49.757 Program Map Table ver(0) pid(0x21) pnum(9494) len(65) Stream #0 pid(0x1222) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) Stream #1 pid(0x1223) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:12:49.760 PMT for output stream 2008-02-15 18:12:49.761 Program Map Table ver(0) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1222) type(video-h264 0x1b) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:12:49.763 RecBase(5:5): SetRecording(0xb1248910) title(Unknown) 2008-02-15 18:12:49.767 DVBRec(5:5): Card opened successfully fd(-1) 2008-02-15 18:12:49.770 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:12:49.772 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:12:49.775 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:12:49.781 TVRec(5): SetFlags(RecorderRunning,RingBufferReady,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:12:49.783 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:12:49.844 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:12:49.994 DevRdB(5): buffer size 65800 KB 2008-02-15 18:12:49.998 DVBSH(5): RunTS(): begin 2008-02-15 18:12:50.000 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:12:50.002 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:12:50.004 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:12:50.006 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:12:50.008 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:12:50.010 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:12:50.022 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:12:50.024 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:12:50.026 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:12:50.027 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:12:50.029 DVBSH(5): AddPIDFilter(0x1222) priority 3 2008-02-15 18:12:50.030 PIDInfo(5): Opening filter for pid 0x1222 2008-02-15 18:12:50.032 DVBSH(5): AddPIDFilter(0x1223) priority 3 2008-02-15 18:12:50.034 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:12:50.091 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 2 (expected 13). 2008-02-15 18:12:50.095 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 11 (expected 15). 2008-02-15 18:12:50.097 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 10 (expected 3). 2008-02-15 18:12:50.128 PID 0x1223 Found Payload Start 2008-02-15 18:12:50.209 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:12:50.214 Empty LocalHostName. 2008-02-15 18:12:50.216 Using localhost value of corbin2 2008-02-15 18:12:50.280 New DB connection, total: 1 2008-02-15 18:12:50.292 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:12:50.298 Closing DB connection named 'DBManager0' 2008-02-15 18:12:50.301 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:12:50.308 Current Schema Version: 1208 2008-02-15 18:12:50.320 New DB connection, total: 2 2008-02-15 18:12:50.322 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:12:50.349 Preview Error: Previewer file '/shares/recordings/LiveTV/486_20080215181241.mpg' is not valid. 2008-02-15 18:12:50.356 Preview Error: Run() file not local: '/shares/recordings/LiveTV/486_20080215181241.mpg' 2008-02-15 18:12:50.371 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/486_20080215181241.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:12:51.853 PID 0x1222 Found Payload Start 2008-02-15 18:13:40.140 TVRec(5): Found channel (9480) on current card(5). 2008-02-15 18:13:40.148 TVRec(5): SetChannel(9480) -- begin 2008-02-15 18:13:40.150 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:13:40.152 TVRec(5): Request: Program(no) channel(9480) input() flags(LiveTV,) 2008-02-15 18:13:40.155 ChannelBase(5): Looking for startchannel '9480' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:13:40.186 ChannelBase(5): Found startchannel '9480' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:13:40.186 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:13:40.188 TVRec(5): HW Tuner: 5->5 2008-02-15 18:13:40.190 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:13:40.192 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:13:40.194 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:13:40.196 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:13:40.197 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:13:40.200 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:13:40.202 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:13:40.206 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:13:40.207 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:13:40.209 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:13:40.213 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:13:40.214 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:13:40.216 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:13:40.217 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:13:40.219 DVBSH(5): RemovePIDFilter(0x1222) 2008-02-15 18:13:40.220 PIDInfo(5): Closing filter for pid 0x1222 2008-02-15 18:13:40.221 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:13:40.223 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:13:40.224 DVBSH(5): RunTS(): shutdown 2008-02-15 18:13:40.246 DVBSH(5): RunTS(): end 2008-02-15 18:13:40.248 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:13:40.251 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:13:40.254 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:13:40.257 DVBChan(5:5): Opening DVB channel 2008-02-15 18:13:40.259 DVBChan(5:5): SetChannelByString(9480): 2008-02-15 18:13:40.273 DVBChan(5:5): 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:13:40.275 DVBChan(5:5): Old Params: 12369800 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:13:40.277 DVBChan(5:5): Tune(): Tuning to 1294760kHz 2008-02-15 18:13:40.292 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:13:40.295 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:13:40.296 DVBChan(5:5): SetChannelByString(9480): Tuned to frequency. 2008-02-15 18:13:40.297 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:13:40.299 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:13:41.376 TVRec(5): StartedRecording(0xb1224a68) fn(/shares/recordings/LiveTV/15480_20080215181340.mpg) 2008-02-15 18:13:41.385 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:13:41.401 TVRec(5): FinishedRecording(Unknown) in recgroup: LiveTV 2008-02-15 18:13:41.404 Chain: Updated endtime for '486_20080215181248' to 20080215181341 2008-02-15 18:13:41.438 Finished recording Unknown: channel 486 2008-02-15 18:13:41.444 Preview: 'myth://192.168.1.121:6543/486_20080215181248.mpg' is not local, replacing with '/shares/recordings/LiveTV/486_20080215181248.mpg', which is local. 2008-02-15 18:13:41.457 Chain: Appended@3 '15480_20080215181340' 2008-02-15 18:13:41.494 TVRec(5): Starting Signal Monitor 2008-02-15 18:13:41.499 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:13:41.501 DVBChan(5:5): Opening DVB channel 2008-02-15 18:13:41.516 DVBSM(5): Can measure Signal Strength 2008-02-15 18:13:41.518 DVBSM(5): Can measure S/N 2008-02-15 18:13:41.519 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:13:41.521 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:13:41.523 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:13:41.524 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:13:41.526 TVRec(5): Signal monitor successfully created 2008-02-15 18:13:41.528 TVRec(5): Setting up table monitoring. 2008-02-15 18:13:41.543 Using profile 'Live TV' to record 2008-02-15 18:13:41.544 TVRec(5): DVB service_id 9480 on net_id 4104 tsid 423 2008-02-15 18:13:41.547 DTVSM(5)::SetDVBService(transport_id: 423, network_id: 4104, service_id: 9480): 2008-02-15 18:13:41.549 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:13:41.551 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:13:41.552 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:13:41.553 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:13:41.555 SM(5)::Start: begin 2008-02-15 18:13:41.557 DVBSH(5): Retuning for rotor completion 2008-02-15 18:13:41.558 SM(5)::Start: end 2008-02-15 18:13:41.560 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:13:41.562 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:13:41.565 DVBChan(5:5): Tune(): Tuning to 1294760kHz 2008-02-15 18:13:41.567 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:13:41.574 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:13:41.576 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:13:41.576 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-15 18:13:41.577 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:13:41.580 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:13:41.581 DummyDTVRecorder -- started 2008-02-15 18:13:41.584 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:13:41.586 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:13:41.588 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:13:41.590 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:13:41.593 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:13:41.596 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:13:41.599 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:13:41.602 TVRec(5): SetChannel(9480) -- end 2008-02-15 18:13:41.606 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:13:41.607 DVBSH(5): RunTS(): begin 2008-02-15 18:13:41.613 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:13:41.616 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:13:41.620 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:13:41.622 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:13:41.625 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:13:41.627 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:13:41.629 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:13:41.631 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:13:41.633 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:13:41.636 DVBSH(5): AddPIDFilter(0x1223) priority 3 2008-02-15 18:13:41.638 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:13:41.713 DVBRec(5:5): SetPAT(9480 on 0x21) 2008-02-15 18:13:41.715 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:13:41.717 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:13:41.718 CreatePATSingleProgram() 2008-02-15 18:13:41.720 PAT in input stream 2008-02-15 18:13:41.721 Program Association Table PSIP tableID(0x0) length(41) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9470 has PID 0x 21 data 0x24 0xfe 0xe0 0x21 program number 9471 has PID 0x 21 data 0x24 0xff 0xe0 0x21 program number 9473 has PID 0x 21 data 0x25 0x 1 0xe0 0x21 program number 9474 has PID 0x 21 data 0x25 0x 2 0xe0 0x21 program number 9480 has PID 0x 21 data 0x25 0x 8 0xe0 0x21 program number 9481 has PID 0x 21 data 0x25 0x 9 0xe0 0x21 program number 9486 has PID 0x 21 data 0x25 0x e 0xe0 0x21 2008-02-15 18:13:41.724 desired_program(9480) pid(0x21) 2008-02-15 18:13:41.725 pmt_pid(0x21) 2008-02-15 18:13:41.727 PAT for output stream 2008-02-15 18:13:41.728 Program Association Table PSIP tableID(0x0) length(13) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:13:41.730 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:13:41.732 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:13:41.737 DVBRec(5:5): SetPMT(9480) 2008-02-15 18:13:41.739 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.741 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:13:41.742 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:13:41.744 CreatePMTSingleProgram() 2008-02-15 18:13:41.745 PMT in input stream 2008-02-15 18:13:41.747 Program Map Table ver(1) pid(0x21) pnum(9480) len(65) Stream #0 pid(0x1322) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) Stream #1 pid(0x1323) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x29) data_size(0) Conditional Access: sid(0x1810) pid(0x129) data_size(0) Conditional Access: sid(0x1801) pid(0x129) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:13:41.750 PMT for output stream 2008-02-15 18:13:41.751 Program Map Table ver(1) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1322) type(video-h264 0x1b) Stream #1 pid(0x1323) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:13:41.754 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.755 DTVSM(5) Error: Wrong PMT; pmt->pn(9486) desired(9480) 2008-02-15 18:13:41.757 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:13:41.758 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:13:41.760 DVBSH(5): AddPIDFilter(0x1322) priority 3 2008-02-15 18:13:41.762 PIDInfo(5): Opening filter for pid 0x1322 2008-02-15 18:13:41.764 DVBSH(5): AddPIDFilter(0x1323) priority 2 2008-02-15 18:13:41.765 PIDInfo(5): Opening filter for pid 0x1323 2008-02-15 18:13:41.767 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:13:41.768 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.771 DTVSM(5) Error: Wrong PMT; pmt->pn(9470) desired(9480) 2008-02-15 18:13:41.773 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.775 DTVSM(5) Error: Wrong PMT; pmt->pn(9471) desired(9480) 2008-02-15 18:13:41.776 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.778 DTVSM(5) Error: Wrong PMT; pmt->pn(9474) desired(9480) 2008-02-15 18:13:41.781 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.784 DTVSM(5) Error: Wrong PMT; pmt->pn(9473) desired(9480) 2008-02-15 18:13:41.786 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:13:41.787 DTVSM(5) Error: Wrong PMT; pmt->pn(9481) desired(9480) 2008-02-15 18:13:41.864 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:13:41.869 Empty LocalHostName. 2008-02-15 18:13:41.870 Using localhost value of corbin2 2008-02-15 18:13:41.934 New DB connection, total: 1 2008-02-15 18:13:41.948 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:41.953 Closing DB connection named 'DBManager0' 2008-02-15 18:13:41.956 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:41.963 Current Schema Version: 1208 2008-02-15 18:13:41.973 New DB connection, total: 2 2008-02-15 18:13:41.976 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:42.001 RingBuf(/shares/recordings/LiveTV/486_20080215181248.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:13:42.693 PID 0x1323 Found Payload Start 2008-02-15 18:13:42.697 PID 0x1322 status: Decrypted 2008-02-15 18:13:42.699 PID 0x1322 Found Payload Start 2008-02-15 18:13:42.701 PID 0x1323 status: Decrypted 2008-02-15 18:13:42.703 Program 9480 status: Decrypted 2008-02-15 18:13:42.704 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:13:42.705 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:13:43.384 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:13:43.386 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:13:43.387 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:13:43.389 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:13:43.401 TVRec(5): Got good signal 2008-02-15 18:13:43.403 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:13:43.405 DVBSM(5): Stop() -- begin 2008-02-15 18:13:43.406 SM(5)::Stop: begin 2008-02-15 18:13:43.421 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:13:43.423 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:13:43.425 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:13:43.426 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:13:43.454 SM(5)::Stop: end 2008-02-15 18:13:43.456 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:13:43.458 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:13:43.491 DVBSH(5): RunTS(): shutdown 2008-02-15 18:13:43.494 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:13:43.496 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:13:43.497 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:13:43.499 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:13:43.501 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:13:43.502 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:13:43.504 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:13:43.506 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:13:43.507 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:13:43.509 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:13:43.510 DVBSH(5): RemovePIDFilter(0x1322) 2008-02-15 18:13:43.512 PIDInfo(5): Closing filter for pid 0x1322 2008-02-15 18:13:43.513 DVBSH(5): RemovePIDFilter(0x1323) 2008-02-15 18:13:43.515 PIDInfo(5): Closing filter for pid 0x1323 2008-02-15 18:13:43.518 DVBSH(5): RunTS(): end 2008-02-15 18:13:43.520 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:13:43.522 DVBSM(5): Stop() -- end 2008-02-15 18:13:43.523 DVBSM(5): Stop() -- begin 2008-02-15 18:13:43.524 SM(5)::Stop: begin 2008-02-15 18:13:43.526 SM(5)::Stop: end 2008-02-15 18:13:43.528 DVBSM(5): Stop() -- end 2008-02-15 18:13:43.529 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:13:43.529 DVBSM(5): Stop() -- begin 2008-02-15 18:13:43.531 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:13:43.533 SM(5)::Stop: begin 2008-02-15 18:13:43.535 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:13:43.537 SM(5)::Stop: end 2008-02-15 18:13:43.541 DVBSM(5): Stop() -- end 2008-02-15 18:13:43.543 SM(5)::Stop: begin 2008-02-15 18:13:43.544 TVRec(5): Restarting Recorder 2008-02-15 18:13:43.545 SM(5)::Stop: end 2008-02-15 18:13:43.547 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:13:43.556 TVRec(5): FinishedRecording(Strange Case of Sherlock Holmes &...) in recgroup: LiveTV 2008-02-15 18:13:43.560 Chain: Updated endtime for '15480_20080215181340' to 20080215181343 2008-02-15 18:13:43.565 Finished recording Strange Case of Sherlock Holmes &...: channel 15480 2008-02-15 18:13:43.575 scheduler: Last message repeated 2 times: Finished recording: Unknown: channel 486 2008-02-15 18:13:43.586 scheduler: Finished recording: Strange Case of Sherlock Holmes &...: channel 15480 2008-02-15 18:13:43.587 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:13:43.589 TVRec(5): GetProgramRingBufferForLiveTV() QString::setNum: Invalid format char '' 2008-02-15 18:13:44.612 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:13:44.618 AFD: Opened codec 0x82a0e30, id(H264) type(Video) 2008-02-15 18:13:44.622 AFD: Opened codec 0x82a1420, id(AC3) type(Audio) 2008-02-15 18:13:44.649 TVRec(5): StartedRecording(0x8899350) fn(/shares/recordings/LiveTV/15480_20080215181343.mpg) 2008-02-15 18:13:44.658 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:13:44.675 TVRec(5): FinishedRecording(Strange Case of Sherlock Holmes &...) in recgroup: LiveTV 2008-02-15 18:13:44.679 Chain: Updated endtime for '15480_20080215181340' to 20080215181344 2008-02-15 18:13:44.715 Finished recording Strange Case of Sherlock Holmes &...: channel 15480 2008-02-15 18:13:44.723 Preview: 'myth://192.168.1.121:6543/15480_20080215181340.mpg' is not local, replacing with '/shares/recordings/LiveTV/15480_20080215181340.mpg', which is local. 2008-02-15 18:13:44.738 Chain: Appended@4 '15480_20080215181343' 2008-02-15 18:13:44.742 RecBase(5:5): SetRingBuffer(0xb13223f0) '/shares/recordings/LiveTV/15480_20080215181343.mpg' 2008-02-15 18:13:44.757 RecBase(5:5): SetRecording(0xb1380800) title(Strange Case of Sherlock Holmes &...) 2008-02-15 18:13:44.768 DTVRec(5): Reset(void) 2008-02-15 18:13:44.771 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:13:44.777 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:13:44.777 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:13:44.782 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:13:44.785 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:13:44.842 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:13:45.021 DevRdB(5): buffer size 65800 KB 2008-02-15 18:13:45.024 DVBSH(5): RunTS(): begin 2008-02-15 18:13:45.026 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:13:45.028 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:13:45.031 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:13:45.033 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:13:45.035 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:13:45.037 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:13:45.039 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:13:45.041 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:13:45.043 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:13:45.045 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:13:45.047 DVBSH(5): AddPIDFilter(0x1322) priority 3 2008-02-15 18:13:45.049 PIDInfo(5): Opening filter for pid 0x1322 2008-02-15 18:13:45.051 DVBSH(5): AddPIDFilter(0x1323) priority 3 2008-02-15 18:13:45.053 PIDInfo(5): Opening filter for pid 0x1323 2008-02-15 18:13:45.097 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 13 (expected 0). 2008-02-15 18:13:45.099 PID 0x1322 Found Payload Start 2008-02-15 18:13:45.101 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 4 (expected 0). 2008-02-15 18:13:45.103 PID 0x1323 Found Payload Start 2008-02-15 18:13:45.105 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 7 (expected 11). 2008-02-15 18:13:45.144 Preview: Grabbed preview '/shares/recordings/LiveTV/486_20080215181248.mpg' 1280x720@69s 2008-02-15 18:13:45.302 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:13:45.308 Empty LocalHostName. 2008-02-15 18:13:45.309 Using localhost value of corbin2 2008-02-15 18:13:45.378 New DB connection, total: 1 2008-02-15 18:13:45.392 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:45.397 Closing DB connection named 'DBManager0' 2008-02-15 18:13:45.401 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:45.407 Current Schema Version: 1208 2008-02-15 18:13:45.416 New DB connection, total: 2 2008-02-15 18:13:45.419 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:13:45.434 Preview Error: Previewer file '/shares/recordings/LiveTV/15480_20080215181340.mpg' is not valid. 2008-02-15 18:13:45.438 Preview Error: Run() file not local: '/shares/recordings/LiveTV/15480_20080215181340.mpg' 2008-02-15 18:13:45.452 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/15480_20080215181340.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:13:45.455 DVBRec(5:5): PID 0x1322 discontinuity detected 2008-02-15 18:14:00.591 TVRec(5): Found channel (9471) on current card(5). 2008-02-15 18:14:00.632 TVRec(5): SetChannel(9471) -- begin 2008-02-15 18:14:00.634 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:00.635 TVRec(5): Request: Program(no) channel(9471) input() flags(LiveTV,) 2008-02-15 18:14:00.637 ChannelBase(5): Looking for startchannel '9471' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:14:00.637 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:00.641 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:00.673 ChannelBase(5): Found startchannel '9471' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:14:00.676 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:00.676 TVRec(5): HW Tuner: 5->5 2008-02-15 18:14:00.678 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:00.680 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:00.682 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:00.684 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:14:00.686 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:00.688 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:14:00.690 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:00.694 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:00.696 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:00.697 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:00.699 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:00.701 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:00.703 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:00.704 DVBSH(5): RemovePIDFilter(0x1322) 2008-02-15 18:14:00.706 PIDInfo(5): Closing filter for pid 0x1322 2008-02-15 18:14:00.707 DVBSH(5): RemovePIDFilter(0x1323) 2008-02-15 18:14:00.708 PIDInfo(5): Closing filter for pid 0x1323 2008-02-15 18:14:00.726 DVBSH(5): RunTS(): end 2008-02-15 18:14:00.728 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:00.730 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:00.732 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:14:00.736 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:00.738 DVBChan(5:5): SetChannelByString(9471): 2008-02-15 18:14:00.752 DVBChan(5:5): 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:14:00.754 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:14:00.756 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:00.757 DVBChan(5:5): SetChannelByString(9471): Tuned to frequency. 2008-02-15 18:14:00.758 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:00.760 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:14:01.843 TVRec(5): StartedRecording(0xb1380218) fn(/shares/recordings/LiveTV/15471_20080215181400.mpg) 2008-02-15 18:14:01.859 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:01.874 TVRec(5): FinishedRecording(Strange Case of Sherlock Holmes &...) in recgroup: LiveTV 2008-02-15 18:14:01.878 Chain: Updated endtime for '15480_20080215181343' to 20080215181401 2008-02-15 18:14:01.910 Finished recording Strange Case of Sherlock Holmes &...: channel 15480 2008-02-15 18:14:01.917 Preview: 'myth://192.168.1.121:6543/15480_20080215181343.mpg' is not local, replacing with '/shares/recordings/LiveTV/15480_20080215181343.mpg', which is local. 2008-02-15 18:14:01.930 Chain: Appended@5 '15471_20080215181400' 2008-02-15 18:14:01.967 TVRec(5): Starting Signal Monitor 2008-02-15 18:14:01.972 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:14:01.974 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:01.975 DVBSM(5): Can measure Signal Strength 2008-02-15 18:14:01.977 DVBSM(5): Can measure S/N 2008-02-15 18:14:01.978 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:14:01.980 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:14:01.981 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:14:01.982 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:14:01.984 TVRec(5): Signal monitor successfully created 2008-02-15 18:14:01.985 TVRec(5): Setting up table monitoring. 2008-02-15 18:14:01.997 Using profile 'Live TV' to record 2008-02-15 18:14:01.999 TVRec(5): DVB service_id 9471 on net_id 4104 tsid 423 2008-02-15 18:14:02.001 DTVSM(5)::SetDVBService(transport_id: 423, network_id: 4104, service_id: 9471): 2008-02-15 18:14:02.003 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:14:02.004 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:14:02.006 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:14:02.007 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:14:02.009 SM(5)::Start: begin 2008-02-15 18:14:02.010 DVBSH(5): Retuning for rotor completion 2008-02-15 18:14:02.011 SM(5)::Start: end 2008-02-15 18:14:02.012 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:14:02.014 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:02.016 DVBChan(5:5): Tune(): Tuning to 1294760kHz 2008-02-15 18:14:02.018 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:02.023 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:02.025 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:14:02.025 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-15 18:14:02.026 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:14:02.028 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:02.030 DummyDTVRecorder -- started 2008-02-15 18:14:02.032 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:14:02.034 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:02.036 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:02.038 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:02.040 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:02.042 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:02.044 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:02.053 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:14:02.053 DVBSH(5): RunTS(): begin 2008-02-15 18:14:02.055 TVRec(5): SetChannel(9471) -- end 2008-02-15 18:14:02.057 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:02.061 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:02.063 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:02.065 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:02.066 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:02.067 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:02.073 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:02.076 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:02.077 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:02.080 DVBSH(5): AddPIDFilter(0x1323) priority 3 2008-02-15 18:14:02.082 PIDInfo(5): Opening filter for pid 0x1323 2008-02-15 18:14:02.088 DVBRec(5:5): SetPAT(9471 on 0x21) 2008-02-15 18:14:02.091 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:14:02.092 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:14:02.094 CreatePATSingleProgram() 2008-02-15 18:14:02.096 PAT in input stream 2008-02-15 18:14:02.097 Program Association Table PSIP tableID(0x0) length(41) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9470 has PID 0x 21 data 0x24 0xfe 0xe0 0x21 program number 9471 has PID 0x 21 data 0x24 0xff 0xe0 0x21 program number 9473 has PID 0x 21 data 0x25 0x 1 0xe0 0x21 program number 9474 has PID 0x 21 data 0x25 0x 2 0xe0 0x21 program number 9480 has PID 0x 21 data 0x25 0x 8 0xe0 0x21 program number 9481 has PID 0x 21 data 0x25 0x 9 0xe0 0x21 program number 9486 has PID 0x 21 data 0x25 0x e 0xe0 0x21 2008-02-15 18:14:02.101 desired_program(9471) pid(0x21) 2008-02-15 18:14:02.103 pmt_pid(0x21) 2008-02-15 18:14:02.104 PAT for output stream 2008-02-15 18:14:02.107 Program Association Table PSIP tableID(0x0) length(13) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:14:02.110 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:02.112 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:02.310 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:02.316 Empty LocalHostName. 2008-02-15 18:14:02.318 Using localhost value of corbin2 2008-02-15 18:14:02.375 New DB connection, total: 1 2008-02-15 18:14:02.388 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:02.393 Closing DB connection named 'DBManager0' 2008-02-15 18:14:02.396 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:02.403 Current Schema Version: 1208 2008-02-15 18:14:02.412 New DB connection, total: 2 2008-02-15 18:14:02.414 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:02.439 RingBuf(/shares/recordings/LiveTV/15480_20080215181343.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:14:02.500 [h264 @ 0xb7276188]non existing PPS referenced 2008-02-15 18:14:02.502 [h264 @ 0xb7276188]decode_slice_header error 2008-02-15 18:14:02.503 [h264 @ 0xb7276188]no frame! 2008-02-15 18:14:02.505 [h264 @ 0xb7276188]non existing PPS referenced 2008-02-15 18:14:02.506 [h264 @ 0xb7276188]decode_slice_header error 2008-02-15 18:14:02.508 [h264 @ 0xb7276188]no frame! 2008-02-15 18:14:02.510 [h264 @ 0xb7276188]non existing PPS referenced 2008-02-15 18:14:02.511 [h264 @ 0xb7276188]decode_slice_header error 2008-02-15 18:14:02.512 [h264 @ 0xb7276188]no frame! 2008-02-15 18:14:02.516 [h264 @ 0xb7276188]non existing PPS referenced 2008-02-15 18:14:02.518 [h264 @ 0xb7276188]decode_slice_header error 2008-02-15 18:14:02.519 [h264 @ 0xb7276188]no frame! 2008-02-15 18:14:02.573 DVBRec(5:5): PID 0x1323 discontinuity detected 2008-02-15 18:14:02.577 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.579 DTVSM(5) Error: Wrong PMT; pmt->pn(9480) desired(9471) 2008-02-15 18:14:02.581 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.582 DTVSM(5) Error: Wrong PMT; pmt->pn(9486) desired(9471) 2008-02-15 18:14:02.584 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.585 DTVSM(5) Error: Wrong PMT; pmt->pn(9470) desired(9471) 2008-02-15 18:14:02.587 DVBRec(5:5): SetPMT(9471) 2008-02-15 18:14:02.588 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.590 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:14:02.591 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:14:02.593 CreatePMTSingleProgram() 2008-02-15 18:14:02.594 PMT in input stream 2008-02-15 18:14:02.595 Program Map Table ver(2) pid(0x21) pnum(9471) len(65) Stream #0 pid(0x1122) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x27) data_size(0) Conditional Access: sid(0x1810) pid(0x127) data_size(0) Conditional Access: sid(0x1801) pid(0x127) data_size(0) Stream #1 pid(0x1123) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x27) data_size(0) Conditional Access: sid(0x1810) pid(0x127) data_size(0) Conditional Access: sid(0x1801) pid(0x127) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:02.597 PMT for output stream 2008-02-15 18:14:02.599 Program Map Table ver(2) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1122) type(video-h264 0x1b) Stream #1 pid(0x1123) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:02.601 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.602 DTVSM(5) Error: Wrong PMT; pmt->pn(9474) desired(9471) 2008-02-15 18:14:02.604 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.605 DTVSM(5) Error: Wrong PMT; pmt->pn(9473) desired(9471) 2008-02-15 18:14:02.607 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:02.608 DTVSM(5) Error: Wrong PMT; pmt->pn(9481) desired(9471) 2008-02-15 18:14:02.612 DVBSH(5): RemovePIDFilter(0x1323) 2008-02-15 18:14:02.613 PIDInfo(5): Closing filter for pid 0x1323 2008-02-15 18:14:02.615 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:14:02.617 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:14:02.618 DVBSH(5): AddPIDFilter(0x1123) priority 2 2008-02-15 18:14:02.620 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:14:02.627 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:14:02.776 PID 0x1122 status: Decrypted 2008-02-15 18:14:02.779 PID 0x1122 status: Unknown 2008-02-15 18:14:03.653 DVBRec(5:5): PID 0x1122 discontinuity detected 2008-02-15 18:14:03.657 PID 0x1122 status: Decrypted 2008-02-15 18:14:03.659 DVBRec(5:5): PID 0x1123 discontinuity detected 2008-02-15 18:14:03.660 PID 0x1122 Found Payload Start 2008-02-15 18:14:03.663 PID 0x1123 status: Decrypted 2008-02-15 18:14:03.665 Program 9471 status: Decrypted 2008-02-15 18:14:03.666 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:14:03.667 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:14:03.670 PID 0x1123 Found Payload Start 2008-02-15 18:14:04.531 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:04.533 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:04.534 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:04.535 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:04.539 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:04.541 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:04.542 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:04.544 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:04.553 TVRec(5): Got good signal 2008-02-15 18:14:04.557 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:14:04.559 DVBSM(5): Stop() -- begin 2008-02-15 18:14:04.560 SM(5)::Stop: begin 2008-02-15 18:14:04.606 SM(5)::Stop: end 2008-02-15 18:14:04.608 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:04.610 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:04.651 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:04.654 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:04.655 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:04.657 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:04.659 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:04.660 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:04.661 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:04.663 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:04.664 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:04.666 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:04.668 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:04.669 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:14:04.670 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:14:04.672 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:14:04.674 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:14:04.676 DVBSH(5): RunTS(): end 2008-02-15 18:14:04.678 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:04.679 DVBSM(5): Stop() -- end 2008-02-15 18:14:04.681 DVBSM(5): Stop() -- begin 2008-02-15 18:14:04.682 SM(5)::Stop: begin 2008-02-15 18:14:04.685 SM(5)::Stop: end 2008-02-15 18:14:04.687 DVBSM(5): Stop() -- end 2008-02-15 18:14:04.688 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:14:04.688 DVBSM(5): Stop() -- begin 2008-02-15 18:14:04.690 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:04.691 SM(5)::Stop: begin 2008-02-15 18:14:04.693 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:04.696 SM(5)::Stop: end 2008-02-15 18:14:04.702 DVBSM(5): Stop() -- end 2008-02-15 18:14:04.704 SM(5)::Stop: begin 2008-02-15 18:14:04.704 TVRec(5): Restarting Recorder 2008-02-15 18:14:04.705 SM(5)::Stop: end 2008-02-15 18:14:04.708 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:14:04.717 TVRec(5): FinishedRecording(Penny Revolution) in recgroup: LiveTV 2008-02-15 18:14:04.729 Chain: Updated endtime for '15471_20080215181400' to 20080215181404 2008-02-15 18:14:04.734 Finished recording Penny Revolution: channel 15471 2008-02-15 18:14:04.746 scheduler: Last message repeated 2 times: Finished recording: Strange Case of Sherlock Holmes &...: channel 15480 2008-02-15 18:14:04.758 scheduler: Finished recording: Penny Revolution: channel 15471 2008-02-15 18:14:04.760 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:04.762 TVRec(5): GetProgramRingBufferForLiveTV() QString::setNum: Invalid format char '' 2008-02-15 18:14:05.032 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:14:05.038 AFD: Opened codec 0x82a1430, id(H264) type(Video) 2008-02-15 18:14:05.041 AFD: Opened codec 0x82a1a20, id(AC3) type(Audio) 2008-02-15 18:14:05.297 [h264 @ 0xb7276188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:14:05.334 [h264 @ 0xb7276188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:14:05.358 [h264 @ 0xb7276188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:14:05.394 [h264 @ 0xb7276188]reference picture missing during reorder 2008-02-15 18:14:05.395 [h264 @ 0xb7276188]reference picture missing during reorder 2008-02-15 18:14:05.422 [h264 @ 0xb7276188]error while decoding MB 32 30, bytestream (-11) 2008-02-15 18:14:05.607 Preview: Grabbed preview '/shares/recordings/LiveTV/15480_20080215181343.mpg' 1440x1088@69s 2008-02-15 18:14:05.818 TVRec(5): StartedRecording(0xb14ff2b8) fn(/shares/recordings/LiveTV/15471_20080215181404.mpg) 2008-02-15 18:14:05.827 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:05.844 TVRec(5): FinishedRecording(Penny Revolution) in recgroup: LiveTV 2008-02-15 18:14:05.847 Chain: Updated endtime for '15471_20080215181400' to 20080215181405 2008-02-15 18:14:05.882 Finished recording Penny Revolution: channel 15471 2008-02-15 18:14:05.889 Preview: 'myth://192.168.1.121:6543/15471_20080215181400.mpg' is not local, replacing with '/shares/recordings/LiveTV/15471_20080215181400.mpg', which is local. 2008-02-15 18:14:05.903 Chain: Appended@6 '15471_20080215181404' 2008-02-15 18:14:05.907 RecBase(5:5): SetRingBuffer(0xb1250570) '/shares/recordings/LiveTV/15471_20080215181404.mpg' 2008-02-15 18:14:05.922 RecBase(5:5): SetRecording(0xb124b7f8) title(Penny Revolution) 2008-02-15 18:14:05.927 DTVRec(5): Reset(void) 2008-02-15 18:14:05.928 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:14:05.932 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:14:05.939 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:05.941 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:05.947 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:05.987 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:06.146 DevRdB(5): buffer size 65800 KB 2008-02-15 18:14:06.149 DVBSH(5): RunTS(): begin 2008-02-15 18:14:06.151 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:06.152 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:06.154 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:06.156 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:06.157 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:06.159 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:06.161 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:06.162 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:06.164 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:06.165 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:06.167 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:14:06.169 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:14:06.170 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:14:06.172 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:14:06.216 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 3 (expected 1). 2008-02-15 18:14:06.218 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 5 (expected 12). 2008-02-15 18:14:06.220 PID 0x1122 Found Payload Start 2008-02-15 18:14:06.222 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 11 (expected 10). 2008-02-15 18:14:06.225 PID 0x1123 Found Payload Start 2008-02-15 18:14:06.362 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:06.369 Empty LocalHostName. 2008-02-15 18:14:06.370 Using localhost value of corbin2 2008-02-15 18:14:06.434 New DB connection, total: 1 2008-02-15 18:14:06.446 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:06.451 Closing DB connection named 'DBManager0' 2008-02-15 18:14:06.454 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:06.459 Current Schema Version: 1208 2008-02-15 18:14:06.468 New DB connection, total: 2 2008-02-15 18:14:06.471 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:06.489 Preview Error: Previewer file '/shares/recordings/LiveTV/15471_20080215181400.mpg' is not valid. 2008-02-15 18:14:06.492 Preview Error: Run() file not local: '/shares/recordings/LiveTV/15471_20080215181400.mpg' 2008-02-15 18:14:06.510 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/15471_20080215181400.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:14:06.945 DVBRec(5:5): PID 0x1122 discontinuity detected 2008-02-15 18:14:21.049 TVRec(5): Found channel (9472) on current card(5). 2008-02-15 18:14:21.070 TVRec(5): SetChannel(9472) -- begin 2008-02-15 18:14:21.072 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:21.073 TVRec(5): Request: Program(no) channel(9472) input() flags(LiveTV,) 2008-02-15 18:14:21.077 ChannelBase(5): Looking for startchannel '9472' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:14:21.112 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:21.114 ChannelBase(5): Found startchannel '9472' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:14:21.115 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:21.119 TVRec(5): HW Tuner: 5->5 2008-02-15 18:14:21.124 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:21.124 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:21.127 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:21.130 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:14:21.133 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:21.136 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:14:21.139 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:21.145 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:21.147 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:21.148 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:21.150 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:21.152 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:21.154 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:21.155 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:21.157 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:14:21.159 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:14:21.160 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:14:21.162 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:14:21.185 DVBSH(5): RunTS(): end 2008-02-15 18:14:21.187 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:21.190 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:21.193 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:14:21.197 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:21.199 DVBChan(5:5): SetChannelByString(9472): 2008-02-15 18:14:21.214 DVBChan(5:5): 12530180 t_8psk a auto auto a a auto a l 2008-02-15 18:14:21.216 DiSEqCDevTree: Changing LNB voltage to 18V 2008-02-15 18:14:21.236 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2008-02-15 18:14:21.238 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f2 2008-02-15 18:14:21.669 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12530180 t_8psk a auto auto a a auto a l 2008-02-15 18:14:21.672 DVBChan(5:5): Tune(): Tuning to 1280180kHz 2008-02-15 18:14:21.688 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:14:21.690 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:21.691 DVBChan(5:5): SetChannelByString(9472): Tuned to frequency. 2008-02-15 18:14:21.692 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:21.694 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:14:22.761 TVRec(5): StartedRecording(0xb124b7e0) fn(/shares/recordings/LiveTV/15472_20080215181421.mpg) 2008-02-15 18:14:22.770 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:22.786 TVRec(5): FinishedRecording(Penny Revolution) in recgroup: LiveTV 2008-02-15 18:14:22.789 Chain: Updated endtime for '15471_20080215181404' to 20080215181422 2008-02-15 18:14:22.823 Finished recording Penny Revolution: channel 15471 2008-02-15 18:14:22.829 Preview: 'myth://192.168.1.121:6543/15471_20080215181404.mpg' is not local, replacing with '/shares/recordings/LiveTV/15471_20080215181404.mpg', which is local. 2008-02-15 18:14:22.843 Chain: Appended@7 '15472_20080215181421' 2008-02-15 18:14:22.847 TVRec(5): Starting Signal Monitor 2008-02-15 18:14:22.849 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:14:22.852 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:22.868 DVBSM(5): Can measure Signal Strength 2008-02-15 18:14:22.872 DVBSM(5): Can measure S/N 2008-02-15 18:14:22.873 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:14:22.875 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:14:22.876 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:14:22.878 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:14:22.879 TVRec(5): Signal monitor successfully created 2008-02-15 18:14:22.881 TVRec(5): Setting up table monitoring. 2008-02-15 18:14:22.896 Using profile 'Live TV' to record 2008-02-15 18:14:22.897 TVRec(5): DVB service_id 9472 on net_id 4104 tsid 422 2008-02-15 18:14:22.900 DTVSM(5)::SetDVBService(transport_id: 422, network_id: 4104, service_id: 9472): 2008-02-15 18:14:22.902 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:14:22.903 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:14:22.905 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:14:22.906 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:14:22.908 SM(5)::Start: begin 2008-02-15 18:14:22.909 DVBSH(5): Retuning for rotor completion 2008-02-15 18:14:22.911 SM(5)::Start: end 2008-02-15 18:14:22.913 DVBChan(5:5): Old Params: 12530180 t_8psk a auto auto a a auto a l DVBChan(5:5): New Params: 12530180 t_8psk a auto auto a a auto a l 2008-02-15 18:14:22.915 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:22.919 DVBChan(5:5): Tune(): Tuning to 1280180kHz 2008-02-15 18:14:22.921 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:22.928 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:22.930 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:14:22.930 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-15 18:14:22.931 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:14:22.934 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:22.936 DummyDTVRecorder -- started 2008-02-15 18:14:22.940 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:14:22.942 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:22.946 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:22.949 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:22.951 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:22.955 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:22.958 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:22.961 TVRec(5): SetChannel(9472) -- end 2008-02-15 18:14:22.965 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:14:22.966 DVBSH(5): RunTS(): begin 2008-02-15 18:14:22.975 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:22.977 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:22.979 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:22.982 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:22.989 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:22.991 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:22.994 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:22.996 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:22.998 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:23.001 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:14:23.006 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:14:23.023 DVBRec(5:5): SetPAT(9472 on 0x21) 2008-02-15 18:14:23.025 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:14:23.027 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:14:23.029 CreatePATSingleProgram() 2008-02-15 18:14:23.031 PAT in input stream 2008-02-15 18:14:23.032 Program Association Table PSIP tableID(0x0) length(41) extension(0x1a6) version(9) current(1) section(0) last_section(0) tsid: 422 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9472 has PID 0x 21 data 0x25 0x 0 0xe0 0x21 program number 9475 has PID 0x 21 data 0x25 0x 3 0xe0 0x21 program number 9476 has PID 0x 21 data 0x25 0x 4 0xe0 0x21 program number 9478 has PID 0x 21 data 0x25 0x 6 0xe0 0x21 program number 9479 has PID 0x 21 data 0x25 0x 7 0xe0 0x21 program number 9482 has PID 0x 21 data 0x25 0x a 0xe0 0x21 program number 9485 has PID 0x 21 data 0x25 0x d 0xe0 0x21 2008-02-15 18:14:23.035 desired_program(9472) pid(0x21) 2008-02-15 18:14:23.036 pmt_pid(0x21) 2008-02-15 18:14:23.038 PAT for output stream 2008-02-15 18:14:23.039 Program Association Table PSIP tableID(0x0) length(13) extension(0x1a6) version(9) current(1) section(0) last_section(0) tsid: 422 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:14:23.041 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:23.043 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:23.064 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.066 DTVSM(5) Error: Wrong PMT; pmt->pn(9485) desired(9472) 2008-02-15 18:14:23.078 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-15 18:14:23.087 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.089 DTVSM(5) Error: Wrong PMT; pmt->pn(9482) desired(9472) 2008-02-15 18:14:23.091 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.093 DTVSM(5) Error: Wrong PMT; pmt->pn(9475) desired(9472) 2008-02-15 18:14:23.106 DVBRec(5:5): SetPMT(9472) 2008-02-15 18:14:23.108 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.110 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:14:23.112 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:14:23.113 CreatePMTSingleProgram() 2008-02-15 18:14:23.115 PMT in input stream 2008-02-15 18:14:23.116 Program Map Table ver(1) pid(0x21) pnum(9472) len(65) Stream #0 pid(0x1222) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x2a) data_size(0) Conditional Access: sid(0x1810) pid(0x12a) data_size(0) Conditional Access: sid(0x1801) pid(0x12a) data_size(0) Stream #1 pid(0x1223) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x2a) data_size(0) Conditional Access: sid(0x1810) pid(0x12a) data_size(0) Conditional Access: sid(0x1801) pid(0x12a) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:23.120 PMT for output stream 2008-02-15 18:14:23.121 Program Map Table ver(1) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1222) type(video-h264 0x1b) Stream #1 pid(0x1223) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:23.123 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.125 DTVSM(5) Error: Wrong PMT; pmt->pn(9476) desired(9472) 2008-02-15 18:14:23.127 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.128 DTVSM(5) Error: Wrong PMT; pmt->pn(9478) desired(9472) 2008-02-15 18:14:23.130 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:14:23.132 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:14:23.134 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:14:23.134 DVBSH(5): AddPIDFilter(0x1222) priority 3 2008-02-15 18:14:23.140 PIDInfo(5): Opening filter for pid 0x1222 2008-02-15 18:14:23.141 DVBSH(5): AddPIDFilter(0x1223) priority 2 2008-02-15 18:14:23.143 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:14:23.146 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:23.148 DTVSM(5) Error: Wrong PMT; pmt->pn(9479) desired(9472) 2008-02-15 18:14:23.243 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:23.248 Empty LocalHostName. 2008-02-15 18:14:23.250 Using localhost value of corbin2 2008-02-15 18:14:23.315 New DB connection, total: 1 2008-02-15 18:14:23.329 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:23.333 Closing DB connection named 'DBManager0' 2008-02-15 18:14:23.337 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:23.343 Current Schema Version: 1208 2008-02-15 18:14:23.352 New DB connection, total: 2 2008-02-15 18:14:23.355 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:23.379 RingBuf(/shares/recordings/LiveTV/15471_20080215181404.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:14:23.438 [h264 @ 0xb72ba188]non existing PPS referenced 2008-02-15 18:14:23.440 [h264 @ 0xb72ba188]decode_slice_header error 2008-02-15 18:14:23.442 [h264 @ 0xb72ba188]no frame! 2008-02-15 18:14:23.448 [h264 @ 0xb72ba188]non existing PPS referenced 2008-02-15 18:14:23.449 [h264 @ 0xb72ba188]decode_slice_header error 2008-02-15 18:14:23.450 [h264 @ 0xb72ba188]no frame! 2008-02-15 18:14:24.076 PID 0x1222 status: Decrypted 2008-02-15 18:14:24.082 PID 0x1223 status: Decrypted 2008-02-15 18:14:24.084 Program 9472 status: Decrypted 2008-02-15 18:14:24.085 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:14:24.087 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:14:24.113 PID 0x1222 Found Payload Start 2008-02-15 18:14:24.184 PID 0x1223 Found Payload Start 2008-02-15 18:14:25.393 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:25.395 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:25.396 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:25.398 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:25.419 TVRec(5): Got good signal 2008-02-15 18:14:25.422 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:14:25.423 DVBSM(5): Stop() -- begin 2008-02-15 18:14:25.425 SM(5)::Stop: begin 2008-02-15 18:14:25.427 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:25.430 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:25.431 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:25.433 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:25.473 SM(5)::Stop: end 2008-02-15 18:14:25.475 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:25.483 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:25.496 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:25.499 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:25.500 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:25.502 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:25.503 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:25.505 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:25.507 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:25.508 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:25.509 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:25.511 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:25.512 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:25.514 DVBSH(5): RemovePIDFilter(0x1222) 2008-02-15 18:14:25.515 PIDInfo(5): Closing filter for pid 0x1222 2008-02-15 18:14:25.516 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:14:25.518 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:14:25.520 DVBSH(5): RunTS(): end 2008-02-15 18:14:25.522 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:25.525 DVBSM(5): Stop() -- end 2008-02-15 18:14:25.526 DVBSM(5): Stop() -- begin 2008-02-15 18:14:25.528 SM(5)::Stop: begin 2008-02-15 18:14:25.529 SM(5)::Stop: end 2008-02-15 18:14:25.530 DVBSM(5): Stop() -- end 2008-02-15 18:14:25.532 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:14:25.532 DVBSM(5): Stop() -- begin 2008-02-15 18:14:25.533 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:25.536 SM(5)::Stop: begin 2008-02-15 18:14:25.539 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:25.541 SM(5)::Stop: end 2008-02-15 18:14:25.546 DVBSM(5): Stop() -- end 2008-02-15 18:14:25.548 SM(5)::Stop: begin 2008-02-15 18:14:25.548 TVRec(5): Restarting Recorder 2008-02-15 18:14:25.549 SM(5)::Stop: end 2008-02-15 18:14:25.552 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:14:25.563 TVRec(5): FinishedRecording(Artstar) in recgroup: LiveTV 2008-02-15 18:14:25.566 Chain: Updated endtime for '15472_20080215181421' to 20080215181425 2008-02-15 18:14:25.571 Finished recording Artstar: channel 15472 2008-02-15 18:14:25.583 scheduler: Last message repeated 2 times: Finished recording: Penny Revolution: channel 15471 2008-02-15 18:14:25.594 scheduler: Finished recording: Artstar: channel 15472 2008-02-15 18:14:25.595 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:25.597 TVRec(5): GetProgramRingBufferForLiveTV() QString::setNum: Invalid format char '' 2008-02-15 18:14:26.032 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:14:26.038 AFD: Opened codec 0x82a0ed0, id(H264) type(Video) 2008-02-15 18:14:26.042 AFD: Opened codec 0x82a14c0, id(AC3) type(Audio) 2008-02-15 18:14:26.297 [h264 @ 0xb72ba188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:14:26.347 [h264 @ 0xb72ba188]reference picture missing during reorder 2008-02-15 18:14:26.350 [h264 @ 0xb72ba188]reference picture missing during reorder 2008-02-15 18:14:26.354 [h264 @ 0xb72ba188]error while decoding MB 86 2, bytestream (-9) 2008-02-15 18:14:26.496 Preview: Grabbed preview '/shares/recordings/LiveTV/15471_20080215181404.mpg' 1440x1088@69s 2008-02-15 18:14:26.654 TVRec(5): StartedRecording(0xb14a80d0) fn(/shares/recordings/LiveTV/15472_20080215181425.mpg) 2008-02-15 18:14:26.664 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:26.680 TVRec(5): FinishedRecording(Artstar) in recgroup: LiveTV 2008-02-15 18:14:26.684 Chain: Updated endtime for '15472_20080215181421' to 20080215181426 2008-02-15 18:14:26.720 Finished recording Artstar: channel 15472 2008-02-15 18:14:26.726 Preview: 'myth://192.168.1.121:6543/15472_20080215181421.mpg' is not local, replacing with '/shares/recordings/LiveTV/15472_20080215181421.mpg', which is local. 2008-02-15 18:14:26.740 Chain: Appended@8 '15472_20080215181425' 2008-02-15 18:14:26.745 RecBase(5:5): SetRingBuffer(0x88afd20) '/shares/recordings/LiveTV/15472_20080215181425.mpg' 2008-02-15 18:14:26.759 RecBase(5:5): SetRecording(0x88b01c0) title(Artstar) 2008-02-15 18:14:26.764 DTVRec(5): Reset(void) 2008-02-15 18:14:26.767 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:14:26.773 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:26.776 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:26.773 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:14:26.779 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:26.828 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:26.968 DevRdB(5): buffer size 65800 KB 2008-02-15 18:14:26.971 DVBSH(5): RunTS(): begin 2008-02-15 18:14:26.972 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:26.974 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:26.976 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:26.977 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:26.979 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:26.980 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:26.982 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:26.983 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:26.985 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:26.987 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:26.988 DVBSH(5): AddPIDFilter(0x1222) priority 3 2008-02-15 18:14:26.990 PIDInfo(5): Opening filter for pid 0x1222 2008-02-15 18:14:26.991 DVBSH(5): AddPIDFilter(0x1223) priority 3 2008-02-15 18:14:26.993 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:14:27.045 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 11 (expected 0). 2008-02-15 18:14:27.047 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 9 (expected 7). 2008-02-15 18:14:27.078 PID 0x1223 Found Payload Start 2008-02-15 18:14:27.247 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:27.252 Empty LocalHostName. 2008-02-15 18:14:27.253 Using localhost value of corbin2 2008-02-15 18:14:27.316 New DB connection, total: 1 2008-02-15 18:14:27.329 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:27.335 Closing DB connection named 'DBManager0' 2008-02-15 18:14:27.342 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:27.346 Current Schema Version: 1208 2008-02-15 18:14:27.355 New DB connection, total: 2 2008-02-15 18:14:27.357 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:27.376 Preview Error: Previewer file '/shares/recordings/LiveTV/15472_20080215181421.mpg' is not valid. 2008-02-15 18:14:27.379 Preview Error: Run() file not local: '/shares/recordings/LiveTV/15472_20080215181421.mpg' 2008-02-15 18:14:27.393 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/15472_20080215181421.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:14:27.432 PID 0x1222 Found Payload Start 2008-02-15 18:14:47.315 TVRec(5): Found channel (9469) on current card(5). 2008-02-15 18:14:47.400 TVRec(5): SetChannel(9469) -- begin 2008-02-15 18:14:47.405 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:47.407 TVRec(5): Request: Program(no) channel(9469) input() flags(LiveTV,) 2008-02-15 18:14:47.409 ChannelBase(5): Looking for startchannel '9469' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:14:47.420 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:47.423 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:47.429 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:47.431 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:47.433 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:47.435 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:47.437 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:47.439 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:47.441 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:47.443 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:47.444 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:47.446 ChannelBase(5): Found startchannel '9469' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:14:47.446 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:47.450 TVRec(5): HW Tuner: 5->5 2008-02-15 18:14:47.451 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:47.453 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:47.455 DVBSH(5): RemovePIDFilter(0x1222) 2008-02-15 18:14:47.457 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:14:47.459 PIDInfo(5): Closing filter for pid 0x1222 2008-02-15 18:14:47.461 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:14:47.464 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:14:47.467 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:14:47.487 DVBSH(5): RunTS(): end 2008-02-15 18:14:47.490 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:47.496 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:14:47.498 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:14:47.504 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:47.506 DVBChan(5:5): SetChannelByString(9469): 2008-02-15 18:14:47.521 DVBChan(5:5): 12486440 t_8psk a auto auto a a auto a r 2008-02-15 18:14:47.522 DiSEqCDevTree: Changing LNB voltage to 13V 2008-02-15 18:14:47.540 DiSEqCDevTree: Changing to DiSEqC switch port 1/4 2008-02-15 18:14:47.542 DiSEqCDevTree: Sending DiSEqC Command: e0 10 38 f0 2008-02-15 18:14:47.971 DVBChan(5:5): Old Params: 12530180 t_8psk a auto auto a a auto a l DVBChan(5:5): New Params: 12486440 t_8psk a auto auto a a auto a r 2008-02-15 18:14:47.973 DVBChan(5:5): Tune(): Tuning to 1236440kHz 2008-02-15 18:14:47.989 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:14:47.991 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:47.992 DVBChan(5:5): SetChannelByString(9469): Tuned to frequency. 2008-02-15 18:14:47.994 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:47.995 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:14:49.061 TVRec(5): StartedRecording(0x88522b8) fn(/shares/recordings/LiveTV/224_20080215181448.mpg) 2008-02-15 18:14:49.071 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:49.087 TVRec(5): FinishedRecording(Artstar) in recgroup: LiveTV 2008-02-15 18:14:49.090 Chain: Updated endtime for '15472_20080215181425' to 20080215181449 2008-02-15 18:14:49.124 Finished recording Artstar: channel 15472 2008-02-15 18:14:49.131 Preview: 'myth://192.168.1.121:6543/15472_20080215181425.mpg' is not local, replacing with '/shares/recordings/LiveTV/15472_20080215181425.mpg', which is local. 2008-02-15 18:14:49.145 Chain: Appended@9 '224_20080215181448' 2008-02-15 18:14:49.152 TVRec(5): Starting Signal Monitor 2008-02-15 18:14:49.156 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:14:49.159 DVBChan(5:5): Opening DVB channel 2008-02-15 18:14:49.176 DVBSM(5): Can measure Signal Strength 2008-02-15 18:14:49.179 DVBSM(5): Can measure S/N 2008-02-15 18:14:49.180 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:14:49.182 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:14:49.183 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:14:49.185 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:14:49.187 TVRec(5): Signal monitor successfully created 2008-02-15 18:14:49.188 TVRec(5): Setting up table monitoring. 2008-02-15 18:14:49.203 Using profile 'Live TV' to record 2008-02-15 18:14:49.205 TVRec(5): DVB service_id 9469 on net_id 4104 tsid 419 2008-02-15 18:14:49.208 DTVSM(5)::SetDVBService(transport_id: 419, network_id: 4104, service_id: 9469): 2008-02-15 18:14:49.209 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:14:49.211 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:14:49.212 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:14:49.214 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:14:49.215 SM(5)::Start: begin 2008-02-15 18:14:49.217 DVBSH(5): Retuning for rotor completion 2008-02-15 18:14:49.218 SM(5)::Start: end 2008-02-15 18:14:49.220 DVBChan(5:5): Old Params: 12486440 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12486440 t_8psk a auto auto a a auto a r 2008-02-15 18:14:49.222 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:49.225 DVBChan(5:5): Tune(): Tuning to 1236440kHz 2008-02-15 18:14:49.227 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:49.233 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:14:49.234 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-15 18:14:49.234 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:14:49.236 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:14:49.238 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:14:49.240 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:14:49.242 DummyDTVRecorder -- started 2008-02-15 18:14:49.244 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:49.246 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:49.248 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:49.250 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:49.252 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:49.254 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:49.258 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:14:49.260 DVBSH(5): RunTS(): begin 2008-02-15 18:14:49.263 TVRec(5): SetChannel(9469) -- end 2008-02-15 18:14:49.264 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:49.271 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:49.276 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:49.281 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:49.289 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:49.292 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:49.292 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:49.298 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:49.300 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:49.302 DVBSH(5): AddPIDFilter(0x1223) priority 3 2008-02-15 18:14:49.304 PIDInfo(5): Opening filter for pid 0x1223 2008-02-15 18:14:49.326 DVBRec(5:5): SetPAT(9469 on 0x21) 2008-02-15 18:14:49.328 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:14:49.330 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:14:49.331 CreatePATSingleProgram() 2008-02-15 18:14:49.333 PAT in input stream 2008-02-15 18:14:49.334 Program Association Table PSIP tableID(0x0) length(57) extension(0x1a3) version(18) current(1) section(0) last_section(0) tsid: 419 programCount: 12 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 5320 has PID 0x 21 data 0x14 0xc8 0xe0 0x21 program number 5330 has PID 0x 21 data 0x14 0xd2 0xe0 0x21 program number 5331 has PID 0x 21 data 0x14 0xd3 0xe0 0x21 program number 5332 has PID 0x 21 data 0x14 0xd4 0xe0 0x21 program number 9468 has PID 0x 21 data 0x24 0xfc 0xe0 0x21 program number 9469 has PID 0x 21 data 0x24 0xfd 0xe0 0x21 program number 9477 has PID 0x 21 data 0x25 0x 5 0xe0 0x21 program number 9487 has PID 0x 21 data 0x25 0x f 0xe0 0x21 program number 9488 has PID 0x 21 data 0x25 0x10 0xe0 0x21 program number 9489 has PID 0x 21 data 0x25 0x11 0xe0 0x21 program number 9490 has PID 0x 21 data 0x25 0x12 0xe0 0x21 2008-02-15 18:14:49.338 desired_program(9469) pid(0x21) 2008-02-15 18:14:49.340 pmt_pid(0x21) 2008-02-15 18:14:49.341 PAT for output stream 2008-02-15 18:14:49.343 Program Association Table PSIP tableID(0x0) length(13) extension(0x1a3) version(18) current(1) section(0) last_section(0) tsid: 419 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:14:49.345 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:49.346 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:49.391 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.393 DTVSM(5) Error: Wrong PMT; pmt->pn(5320) desired(9469) 2008-02-15 18:14:49.394 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.396 DTVSM(5) Error: Wrong PMT; pmt->pn(5330) desired(9469) 2008-02-15 18:14:49.413 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.415 DTVSM(5) Error: Wrong PMT; pmt->pn(5331) desired(9469) 2008-02-15 18:14:49.417 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.418 DTVSM(5) Error: Wrong PMT; pmt->pn(5332) desired(9469) 2008-02-15 18:14:49.419 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-15 18:14:49.420 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.424 DTVSM(5) Error: Wrong PMT; pmt->pn(9468) desired(9469) 2008-02-15 18:14:49.432 DVBRec(5:5): SetPMT(9469) 2008-02-15 18:14:49.435 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.437 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:14:49.439 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:14:49.440 CreatePMTSingleProgram() 2008-02-15 18:14:49.441 PMT in input stream 2008-02-15 18:14:49.443 Program Map Table ver(3) pid(0x21) pnum(9469) len(65) Stream #0 pid(0x1122) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x27) data_size(0) Conditional Access: sid(0x1810) pid(0x127) data_size(0) Conditional Access: sid(0x1801) pid(0x127) data_size(0) Stream #1 pid(0x1123) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x27) data_size(0) Conditional Access: sid(0x1810) pid(0x127) data_size(0) Conditional Access: sid(0x1801) pid(0x127) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:49.445 PMT for output stream 2008-02-15 18:14:49.446 Program Map Table ver(3) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1122) type(video-h264 0x1b) Stream #1 pid(0x1123) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:14:49.448 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.449 DTVSM(5) Error: Wrong PMT; pmt->pn(9477) desired(9469) 2008-02-15 18:14:49.451 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.453 DTVSM(5) Error: Wrong PMT; pmt->pn(9487) desired(9469) 2008-02-15 18:14:49.454 DVBSH(5): RemovePIDFilter(0x1223) 2008-02-15 18:14:49.455 PIDInfo(5): Closing filter for pid 0x1223 2008-02-15 18:14:49.457 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:14:49.459 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:14:49.460 DVBSH(5): AddPIDFilter(0x1123) priority 2 2008-02-15 18:14:49.462 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:14:49.464 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.466 DTVSM(5) Error: Wrong PMT; pmt->pn(9488) desired(9469) 2008-02-15 18:14:49.467 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.469 DTVSM(5) Error: Wrong PMT; pmt->pn(9489) desired(9469) 2008-02-15 18:14:49.472 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:14:49.474 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:14:49.477 DTVSM(5) Error: Wrong PMT; pmt->pn(9490) desired(9469) 2008-02-15 18:14:49.535 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:49.539 Empty LocalHostName. 2008-02-15 18:14:49.540 Using localhost value of corbin2 2008-02-15 18:14:49.599 New DB connection, total: 1 2008-02-15 18:14:49.624 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:49.630 Closing DB connection named 'DBManager0' 2008-02-15 18:14:49.633 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:49.642 Current Schema Version: 1208 2008-02-15 18:14:49.652 New DB connection, total: 2 2008-02-15 18:14:49.654 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:49.677 RingBuf(/shares/recordings/LiveTV/15472_20080215181425.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:14:50.052 PID 0x1123 Found Payload Start 2008-02-15 18:14:50.055 PID 0x1122 status: Decrypted 2008-02-15 18:14:50.057 PID 0x1122 Found Payload Start 2008-02-15 18:14:50.081 PID 0x1123 status: Decrypted 2008-02-15 18:14:50.084 Program 9469 status: Decrypted 2008-02-15 18:14:50.085 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:14:50.086 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:14:51.622 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:51.625 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:51.626 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:51.628 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:51.631 TVRec(5): Got good signal 2008-02-15 18:14:51.634 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:14:51.635 DVBSM(5): Stop() -- begin 2008-02-15 18:14:51.636 SM(5)::Stop: begin 2008-02-15 18:14:51.656 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:14:51.658 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:14:51.659 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:14:51.660 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:14:51.684 SM(5)::Stop: end 2008-02-15 18:14:51.687 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:14:51.689 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:14:51.691 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:14:51.694 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:14:51.695 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:14:51.697 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:14:51.698 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:14:51.700 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:14:51.701 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:14:51.702 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:14:51.704 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:14:51.705 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:14:51.707 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:14:51.708 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:14:51.710 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:14:51.711 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:14:51.764 DVBSH(5): RunTS(): shutdown 2008-02-15 18:14:51.767 DVBSH(5): RunTS(): end 2008-02-15 18:14:51.769 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:14:51.770 DVBSM(5): Stop() -- end 2008-02-15 18:14:51.772 DVBSM(5): Stop() -- begin 2008-02-15 18:14:51.773 SM(5)::Stop: begin 2008-02-15 18:14:51.774 SM(5)::Stop: end 2008-02-15 18:14:51.776 DVBSM(5): Stop() -- end 2008-02-15 18:14:51.777 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:14:51.777 DVBSM(5): Stop() -- begin 2008-02-15 18:14:51.778 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:51.780 SM(5)::Stop: begin 2008-02-15 18:14:51.782 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:14:51.785 SM(5)::Stop: end 2008-02-15 18:14:51.789 DVBSM(5): Stop() -- end 2008-02-15 18:14:51.791 SM(5)::Stop: begin 2008-02-15 18:14:51.791 TVRec(5): Restarting Recorder 2008-02-15 18:14:51.793 SM(5)::Stop: end 2008-02-15 18:14:51.795 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:14:51.804 TVRec(5): FinishedRecording(Boost Mobile Rock Corps L.A. '07) in recgroup: LiveTV 2008-02-15 18:14:51.807 Chain: Updated endtime for '224_20080215181448' to 20080215181451 2008-02-15 18:14:51.813 Finished recording Boost Mobile Rock Corps L.A. '07: channel 224 2008-02-15 18:14:51.824 scheduler: Last message repeated 2 times: Finished recording: Artstar: channel 15472 2008-02-15 18:14:51.835 scheduler: Finished recording: Boost Mobile Rock Corps L.A. '07: channel 224 2008-02-15 18:14:51.837 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:14:51.838 TVRec(5): GetProgramRingBufferForLiveTV() QString::setNum: Invalid format char '' 2008-02-15 18:14:52.322 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:14:52.327 AFD: Opened codec 0x82a12b0, id(H264) type(Video) 2008-02-15 18:14:52.330 AFD: Opened codec 0x82a18a0, id(AC3) type(Audio) 2008-02-15 18:14:52.918 Preview: Grabbed preview '/shares/recordings/LiveTV/15472_20080215181425.mpg' 1440x1088@69s 2008-02-15 18:14:52.925 TVRec(5): StartedRecording(0xb1224720) fn(/shares/recordings/LiveTV/224_20080215181451.mpg) 2008-02-15 18:14:52.935 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:14:52.951 TVRec(5): FinishedRecording(Boost Mobile Rock Corps L.A. '07) in recgroup: LiveTV 2008-02-15 18:14:52.955 Chain: Updated endtime for '224_20080215181448' to 20080215181452 2008-02-15 18:14:52.989 Finished recording Boost Mobile Rock Corps L.A. '07: channel 224 2008-02-15 18:14:52.995 Preview: 'myth://192.168.1.121:6543/224_20080215181448.mpg' is not local, replacing with '/shares/recordings/LiveTV/224_20080215181448.mpg', which is local. 2008-02-15 18:14:53.011 Chain: Appended@10 '224_20080215181451' 2008-02-15 18:14:53.016 RecBase(5:5): SetRingBuffer(0xb14cdf28) '/shares/recordings/LiveTV/224_20080215181451.mpg' 2008-02-15 18:14:53.029 RecBase(5:5): SetRecording(0xb12762c0) title(Boost Mobile Rock Corps L.A. '07) 2008-02-15 18:14:53.034 DTVRec(5): Reset(void) 2008-02-15 18:14:53.036 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:14:53.040 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:14:53.042 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:14:53.040 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:14:53.044 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:14:53.109 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:14:53.265 DevRdB(5): buffer size 65800 KB 2008-02-15 18:14:53.268 DVBSH(5): RunTS(): begin 2008-02-15 18:14:53.270 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:14:53.271 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:14:53.273 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:14:53.275 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:14:53.277 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:14:53.279 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:14:53.281 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:14:53.283 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:14:53.285 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:14:53.287 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:14:53.292 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:14:53.294 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:14:53.296 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:14:53.298 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:14:53.342 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 9 (expected 4). 2008-02-15 18:14:53.344 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 13 (expected 0). 2008-02-15 18:14:53.348 PID 0x1122 Found Payload Start 2008-02-15 18:14:53.350 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 1 (expected 4). 2008-02-15 18:14:53.405 PID 0x1123 Found Payload Start 2008-02-15 18:14:53.464 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:14:53.468 Empty LocalHostName. 2008-02-15 18:14:53.469 Using localhost value of corbin2 2008-02-15 18:14:53.529 New DB connection, total: 1 2008-02-15 18:14:53.542 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:53.548 Closing DB connection named 'DBManager0' 2008-02-15 18:14:53.551 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:53.556 Current Schema Version: 1208 2008-02-15 18:14:53.564 New DB connection, total: 2 2008-02-15 18:14:53.567 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:14:53.585 Preview Error: Previewer file '/shares/recordings/LiveTV/224_20080215181448.mpg' is not valid. 2008-02-15 18:14:53.588 Preview Error: Run() file not local: '/shares/recordings/LiveTV/224_20080215181448.mpg' 2008-02-15 18:14:53.602 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/224_20080215181448.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:14:54.306 DVBRec(5:5): PID 0x1123 discontinuity detected 2008-02-15 18:14:55.445 DVBRec(5:5): PID 0x1122 discontinuity detected 2008-02-15 18:14:57.435 Expiring 25 MBytes for 1504 @ Fri Feb 15 17:00:00 2008 => The Princess Bride 2008-02-15 18:14:57.449 autoexpire: Expiring Program: Expiring 25 MBytes for 1504 @ Fri Feb 15 17:00:00 2008 => The Princess Bride 2008-02-15 18:14:57.451 Expiring 0 MBytes for 486 @ Fri Feb 15 18:12:41 2008 => Unknown 2008-02-15 18:14:57.468 autoexpire: Expiring Program: Expiring 0 MBytes for 486 @ Fri Feb 15 18:12:41 2008 => Unknown 2008-02-15 18:15:07.593 TVRec(5): Found channel (9470) on current card(5). 2008-02-15 18:15:07.673 TVRec(5): SetChannel(9470) -- begin 2008-02-15 18:15:07.674 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:07.676 TVRec(5): Request: Program(no) channel(9470) input() flags(LiveTV,) 2008-02-15 18:15:07.678 ChannelBase(5): Looking for startchannel '9470' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:15:07.697 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:15:07.699 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:15:07.712 ChannelBase(5): Found startchannel '9470' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:15:07.715 TVRec(5): HW Tuner: 5->5 2008-02-15 18:15:07.717 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:07.718 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:15:07.720 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:15:07.725 DVBSH(5): RunTS(): shutdown 2008-02-15 18:15:07.727 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:15:07.728 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:15:07.730 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:15:07.732 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:15:07.734 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:15:07.735 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:15:07.737 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:15:07.739 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:15:07.740 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:15:07.741 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:15:07.743 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:15:07.744 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:15:07.746 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:15:07.748 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:15:07.768 DVBSH(5): RunTS(): end 2008-02-15 18:15:07.770 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:15:07.773 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:07.775 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:15:07.778 DVBChan(5:5): Opening DVB channel 2008-02-15 18:15:07.780 DVBChan(5:5): SetChannelByString(9470): 2008-02-15 18:15:07.795 DVBChan(5:5): 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:15:07.797 DVBChan(5:5): Old Params: 12486440 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:15:07.798 DVBChan(5:5): Tune(): Tuning to 1294760kHz 2008-02-15 18:15:07.814 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:15:07.816 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:15:07.818 DVBChan(5:5): SetChannelByString(9470): Tuned to frequency. 2008-02-15 18:15:07.819 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:15:07.820 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:15:08.883 TVRec(5): StartedRecording(0x8899350) fn(/shares/recordings/LiveTV/15470_20080215181507.mpg) 2008-02-15 18:15:08.893 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:15:08.907 TVRec(5): FinishedRecording(Boost Mobile Rock Corps L.A. '07) in recgroup: LiveTV 2008-02-15 18:15:08.910 Chain: Updated endtime for '224_20080215181451' to 20080215181508 2008-02-15 18:15:08.942 Finished recording Boost Mobile Rock Corps L.A. '07: channel 224 2008-02-15 18:15:08.948 Preview: 'myth://192.168.1.121:6543/224_20080215181451.mpg' is not local, replacing with '/shares/recordings/LiveTV/224_20080215181451.mpg', which is local. 2008-02-15 18:15:08.962 Chain: Appended@11 '15470_20080215181507' 2008-02-15 18:15:08.966 TVRec(5): Starting Signal Monitor 2008-02-15 18:15:08.967 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:15:08.970 DVBChan(5:5): Opening DVB channel 2008-02-15 18:15:08.985 DVBSM(5): Can measure Signal Strength 2008-02-15 18:15:08.989 DVBSM(5): Can measure S/N 2008-02-15 18:15:08.991 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:15:08.992 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:15:08.994 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:15:08.995 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:15:08.997 TVRec(5): Signal monitor successfully created 2008-02-15 18:15:08.998 TVRec(5): Setting up table monitoring. 2008-02-15 18:15:09.011 Using profile 'Live TV' to record 2008-02-15 18:15:09.013 TVRec(5): DVB service_id 9470 on net_id 4104 tsid 423 2008-02-15 18:15:09.016 DTVSM(5)::SetDVBService(transport_id: 423, network_id: 4104, service_id: 9470): 2008-02-15 18:15:09.017 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:15:09.019 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:15:09.020 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:15:09.022 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:15:09.023 SM(5)::Start: begin 2008-02-15 18:15:09.025 DVBSH(5): Retuning for rotor completion 2008-02-15 18:15:09.026 SM(5)::Start: end 2008-02-15 18:15:09.027 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12544760 t_8psk a auto auto a a auto a r 2008-02-15 18:15:09.029 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:09.032 DVBChan(5:5): Tune(): Tuning to 1294760kHz 2008-02-15 18:15:09.034 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:09.038 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:09.040 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:15:09.041 dvbchannel.cpp:wait_for_backend: Status: Signal,Carrier,FEC Stable,Sync,Lock, 2008-02-15 18:15:09.041 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:15:09.043 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:15:09.045 DummyDTVRecorder -- started 2008-02-15 18:15:09.047 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:15:09.049 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:09.051 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:15:09.053 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:09.055 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:15:09.057 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:09.059 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:15:09.061 TVRec(5): SetChannel(9470) -- end 2008-02-15 18:15:09.065 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:15:09.077 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:15:09.068 DVBSH(5): RunTS(): begin 2008-02-15 18:15:09.082 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:15:09.084 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:15:09.088 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:15:09.090 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:15:09.092 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:15:09.094 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:15:09.096 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:15:09.098 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:15:09.100 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:15:09.102 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:15:09.123 DVBRec(5:5): SetPAT(9470 on 0x21) 2008-02-15 18:15:09.125 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:15:09.126 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:15:09.128 CreatePATSingleProgram() 2008-02-15 18:15:09.130 PAT in input stream 2008-02-15 18:15:09.133 Program Association Table PSIP tableID(0x0) length(41) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 9470 has PID 0x 21 data 0x24 0xfe 0xe0 0x21 program number 9471 has PID 0x 21 data 0x24 0xff 0xe0 0x21 program number 9473 has PID 0x 21 data 0x25 0x 1 0xe0 0x21 program number 9474 has PID 0x 21 data 0x25 0x 2 0xe0 0x21 program number 9480 has PID 0x 21 data 0x25 0x 8 0xe0 0x21 program number 9481 has PID 0x 21 data 0x25 0x 9 0xe0 0x21 program number 9486 has PID 0x 21 data 0x25 0x e 0xe0 0x21 2008-02-15 18:15:09.136 desired_program(9470) pid(0x21) 2008-02-15 18:15:09.138 pmt_pid(0x21) 2008-02-15 18:15:09.139 PAT for output stream 2008-02-15 18:15:09.140 Program Association Table PSIP tableID(0x0) length(13) extension(0x1a7) version(5) current(1) section(0) last_section(0) tsid: 423 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:15:09.142 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:15:09.144 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:15:09.157 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.159 DTVSM(5) Error: Wrong PMT; pmt->pn(9471) desired(9470) 2008-02-15 18:15:09.161 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.162 DTVSM(5) Error: Wrong PMT; pmt->pn(9474) desired(9470) 2008-02-15 18:15:09.180 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.182 DTVSM(5) Error: Wrong PMT; pmt->pn(9473) desired(9470) 2008-02-15 18:15:09.183 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.185 DTVSM(5) Error: Wrong PMT; pmt->pn(9481) desired(9470) 2008-02-15 18:15:09.188 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-15 18:15:09.199 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.203 DTVSM(5) Error: Wrong PMT; pmt->pn(9480) desired(9470) 2008-02-15 18:15:09.206 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.207 DTVSM(5) Error: Wrong PMT; pmt->pn(9486) desired(9470) 2008-02-15 18:15:09.221 DVBRec(5:5): SetPMT(9470) 2008-02-15 18:15:09.223 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:09.224 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:15:09.226 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:15:09.227 CreatePMTSingleProgram() 2008-02-15 18:15:09.229 PMT in input stream 2008-02-15 18:15:09.230 Program Map Table ver(1) pid(0x21) pnum(9470) len(65) Stream #0 pid(0x1022) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x26) data_size(0) Conditional Access: sid(0x1810) pid(0x126) data_size(0) Conditional Access: sid(0x1801) pid(0x126) data_size(0) Stream #1 pid(0x1023) type(audio-ac3 0x81) Conditional Access: sid(0x1816) pid(0x26) data_size(0) Conditional Access: sid(0x1810) pid(0x126) data_size(0) Conditional Access: sid(0x1801) pid(0x126) data_size(0) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:15:09.233 PMT for output stream 2008-02-15 18:15:09.234 Program Map Table ver(1) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1022) type(video-h264 0x1b) Stream #1 pid(0x1023) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:15:09.236 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:15:09.238 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:15:09.239 DVBSH(5): AddPIDFilter(0x1022) priority 3 2008-02-15 18:15:09.241 PIDInfo(5): Opening filter for pid 0x1022 2008-02-15 18:15:09.242 DVBSH(5): AddPIDFilter(0x1023) priority 2 2008-02-15 18:15:09.244 PIDInfo(5): Opening filter for pid 0x1023 2008-02-15 18:15:09.248 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:15:09.360 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:15:09.365 Empty LocalHostName. 2008-02-15 18:15:09.367 Using localhost value of corbin2 2008-02-15 18:15:09.429 New DB connection, total: 1 2008-02-15 18:15:09.443 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:09.448 Closing DB connection named 'DBManager0' 2008-02-15 18:15:09.451 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:09.457 Current Schema Version: 1208 2008-02-15 18:15:09.467 New DB connection, total: 2 2008-02-15 18:15:09.470 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:09.492 RingBuf(/shares/recordings/LiveTV/224_20080215181451.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:15:09.550 [h264 @ 0xb7347188]non existing PPS referenced 2008-02-15 18:15:09.552 [h264 @ 0xb7347188]decode_slice_header error 2008-02-15 18:15:09.554 [h264 @ 0xb7347188]no frame! 2008-02-15 18:15:09.556 [h264 @ 0xb7347188]non existing PPS referenced 2008-02-15 18:15:09.557 [h264 @ 0xb7347188]decode_slice_header error 2008-02-15 18:15:09.559 [h264 @ 0xb7347188]no frame! 2008-02-15 18:15:09.570 [h264 @ 0xb7347188]non existing PPS referenced 2008-02-15 18:15:09.571 [h264 @ 0xb7347188]decode_slice_header error 2008-02-15 18:15:09.573 [h264 @ 0xb7347188]no frame! 2008-02-15 18:15:09.679 PID 0x1022 status: Decrypted 2008-02-15 18:15:09.682 PID 0x1022 Found Payload Start 2008-02-15 18:15:09.713 PID 0x1023 status: Decrypted 2008-02-15 18:15:09.714 Program 9470 status: Decrypted 2008-02-15 18:15:09.716 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:15:09.717 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:15:09.719 PID 0x1023 Found Payload Start 2008-02-15 18:15:10.801 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:15:10.804 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:10.806 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:15:10.807 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:10.819 TVRec(5): Got good signal 2008-02-15 18:15:10.822 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:15:10.823 DVBSM(5): Stop() -- begin 2008-02-15 18:15:10.824 SM(5)::Stop: begin 2008-02-15 18:15:10.836 DTVSM(5)::SetNIT(): net_id = 4104 2008-02-15 18:15:10.838 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:10.840 DTVSM(5)::setNIT(): nid = 4104 2008-02-15 18:15:10.841 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:10.874 SM(5)::Stop: end 2008-02-15 18:15:10.877 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:15:10.878 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:15:10.907 DVBSH(5): RunTS(): shutdown 2008-02-15 18:15:10.909 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:15:10.911 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:15:10.913 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:15:10.914 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:15:10.916 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:15:10.917 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:15:10.919 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:15:10.920 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:15:10.922 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:15:10.923 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:15:10.925 DVBSH(5): RemovePIDFilter(0x1022) 2008-02-15 18:15:10.926 PIDInfo(5): Closing filter for pid 0x1022 2008-02-15 18:15:10.928 DVBSH(5): RemovePIDFilter(0x1023) 2008-02-15 18:15:10.929 PIDInfo(5): Closing filter for pid 0x1023 2008-02-15 18:15:10.931 DVBSH(5): RunTS(): end 2008-02-15 18:15:10.933 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:15:10.935 DVBSM(5): Stop() -- end 2008-02-15 18:15:10.937 DVBSM(5): Stop() -- begin 2008-02-15 18:15:10.938 SM(5)::Stop: begin 2008-02-15 18:15:10.939 SM(5)::Stop: end 2008-02-15 18:15:10.941 DVBSM(5): Stop() -- end 2008-02-15 18:15:10.942 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:15:10.942 DVBSM(5): Stop() -- begin 2008-02-15 18:15:10.944 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:10.946 SM(5)::Stop: begin 2008-02-15 18:15:10.947 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:10.949 SM(5)::Stop: end 2008-02-15 18:15:10.954 DVBSM(5): Stop() -- end 2008-02-15 18:15:10.956 SM(5)::Stop: begin 2008-02-15 18:15:10.957 TVRec(5): Restarting Recorder 2008-02-15 18:15:10.957 SM(5)::Stop: end 2008-02-15 18:15:10.960 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:15:10.969 TVRec(5): FinishedRecording(Tom Petty and the Heartbreakers) in recgroup: LiveTV 2008-02-15 18:15:10.972 Chain: Updated endtime for '15470_20080215181507' to 20080215181510 2008-02-15 18:15:10.977 Finished recording Tom Petty and the Heartbreakers: channel 15470 2008-02-15 18:15:10.988 scheduler: Last message repeated 2 times: Finished recording: Boost Mobile Rock Corps L.A. '07: channel 224 2008-02-15 18:15:10.999 scheduler: Finished recording: Tom Petty and the Heartbreakers: channel 15470 2008-02-15 18:15:11.000 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:15:11.002 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:15:12.059 TVRec(5): StartedRecording(0xb121d0e8) fn(/shares/recordings/LiveTV/15470_20080215181511.mpg) 2008-02-15 18:15:12.067 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:15:12.082 TVRec(5): FinishedRecording(Tom Petty and the Heartbreakers) in recgroup: LiveTV 2008-02-15 18:15:12.086 Chain: Updated endtime for '15470_20080215181507' to 20080215181512 QString::setNum: Invalid format char '' 2008-02-15 18:15:12.111 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:15:12.116 AFD: Opened codec 0x82a1180, id(H264) type(Video) 2008-02-15 18:15:12.119 Finished recording Tom Petty and the Heartbreakers: channel 15470 2008-02-15 18:15:12.119 AFD: Opened codec 0x82a1670, id(AC3) type(Audio) 2008-02-15 18:15:12.126 Preview: 'myth://192.168.1.121:6543/15470_20080215181507.mpg' is not local, replacing with '/shares/recordings/LiveTV/15470_20080215181507.mpg', which is local. 2008-02-15 18:15:12.144 Chain: Appended@12 '15470_20080215181511' 2008-02-15 18:15:12.149 RecBase(5:5): SetRingBuffer(0xb1391148) '/shares/recordings/LiveTV/15470_20080215181511.mpg' 2008-02-15 18:15:12.165 RecBase(5:5): SetRecording(0xb138bdf0) title(Tom Petty and the Heartbreakers) 2008-02-15 18:15:12.172 DTVRec(5): Reset(void) 2008-02-15 18:15:12.175 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:15:12.180 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:15:12.210 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:15:12.214 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:15:12.217 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:15:12.228 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:15:12.465 DevRdB(5): buffer size 65800 KB 2008-02-15 18:15:12.468 DVBSH(5): RunTS(): begin 2008-02-15 18:15:12.470 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:15:12.472 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:15:12.474 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:15:12.476 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:15:12.478 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:15:12.480 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:15:12.483 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:15:12.486 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:15:12.489 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:15:12.492 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:15:12.493 [h264 @ 0xb7347188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:12.495 DVBSH(5): AddPIDFilter(0x1022) priority 3 2008-02-15 18:15:12.497 PIDInfo(5): Opening filter for pid 0x1022 2008-02-15 18:15:12.500 DVBSH(5): AddPIDFilter(0x1023) priority 3 2008-02-15 18:15:12.502 PIDInfo(5): Opening filter for pid 0x1023 2008-02-15 18:15:12.538 [h264 @ 0xb7347188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:12.553 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 7 (expected 2). 2008-02-15 18:15:12.555 PID 0x1022 Found Payload Start 2008-02-15 18:15:12.558 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 6 (expected 9). 2008-02-15 18:15:12.589 PID 0x1023 Found Payload Start 2008-02-15 18:15:12.594 [h264 @ 0xb7347188]warning: first frame is no keyframe 2008-02-15 18:15:12.600 [h264 @ 0xb7347188]reference picture missing during reorder 2008-02-15 18:15:12.602 [h264 @ 0xb7347188]reference picture missing during reorder 2008-02-15 18:15:12.650 [h264 @ 0xb7347188]error while decoding MB 83 52, bytestream (-13) 2008-02-15 18:15:12.762 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:15:12.766 Empty LocalHostName. 2008-02-15 18:15:12.767 Using localhost value of corbin2 2008-02-15 18:15:12.831 New DB connection, total: 1 2008-02-15 18:15:12.846 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:12.851 Closing DB connection named 'DBManager0' 2008-02-15 18:15:12.855 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:12.860 Current Schema Version: 1208 2008-02-15 18:15:12.869 New DB connection, total: 2 2008-02-15 18:15:12.872 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:12.892 Preview Error: Previewer file '/shares/recordings/LiveTV/15470_20080215181507.mpg' is not valid. 2008-02-15 18:15:12.895 Preview Error: Run() file not local: '/shares/recordings/LiveTV/15470_20080215181507.mpg' 2008-02-15 18:15:12.910 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/15470_20080215181507.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:15:12.944 Preview: Grabbed preview '/shares/recordings/LiveTV/224_20080215181451.mpg' 1440x1088@69s 2008-02-15 18:15:13.525 DVBRec(5:5): PID 0x1022 discontinuity detected 2008-02-15 18:15:31.067 TVRec(5): Found channel (9432) on current card(5). 2008-02-15 18:15:31.086 ChannelBase(5): Failed to find channel(9432) on current input (DiSEqC v1.3 Input 5) of card (5). 2008-02-15 18:15:31.089 ChannelBase(5): Found channel(9432) on another input (DiSEqC v1.3 Input 1) of card (5). 2008-02-15 18:15:31.120 TVRec(5): SetChannel(9432) -- begin 2008-02-15 18:15:31.121 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:31.123 TVRec(5): Request: Program(no) channel(9432) input() flags(LiveTV,) 2008-02-15 18:15:31.126 ChannelBase(5): Looking for startchannel '9432' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:15:31.129 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:15:31.136 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:15:31.149 ChannelBase(5): Found startchannel '9432' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:15:31.154 TVRec(5): HW Tuner: 5->5 2008-02-15 18:15:31.156 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:31.157 DVBSH(5): RunTS(): shutdown 2008-02-15 18:15:31.158 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:15:31.161 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:15:31.164 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:15:31.167 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:15:31.173 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:15:31.175 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:15:31.176 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:15:31.178 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:15:31.179 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:15:31.181 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:15:31.182 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:15:31.184 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:15:31.185 DVBSH(5): RemovePIDFilter(0x1022) 2008-02-15 18:15:31.187 PIDInfo(5): Closing filter for pid 0x1022 2008-02-15 18:15:31.188 DVBSH(5): RemovePIDFilter(0x1023) 2008-02-15 18:15:31.190 PIDInfo(5): Closing filter for pid 0x1023 2008-02-15 18:15:31.206 DVBSH(5): RunTS(): end 2008-02-15 18:15:31.208 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:15:31.211 TVRec(5): ClearFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:15:31.214 TVRec(5): Recorder paused, calling TuningFrequency 2008-02-15 18:15:31.219 DVBChan(5:5): Opening DVB channel 2008-02-15 18:15:31.220 DVBChan(5:5): SetChannelByString(9432): 2008-02-15 18:15:31.223 ChannelBase(5): Failed to find channel(9432) on current input (DiSEqC v1.3 Input 5) of card (5). 2008-02-15 18:15:31.226 ChannelBase(5): Found channel(9432) on another input (DiSEqC v1.3 Input 1) of card (5). 2008-02-15 18:15:31.239 DVBChan(5:5): 12457280 t_8psk a auto auto a a auto a r 2008-02-15 18:15:31.242 DiSEqCDevTree: Overriding voltage to 18V for faster rotor movement 2008-02-15 18:15:31.243 DiSEqCDevTree: Changing LNB voltage to 18V 2008-02-15 18:15:31.262 DiSEqCDevTree: USALS Rotor - Goto -110 (Azimuth -36.0197) 2008-02-15 18:15:31.264 DiSEqCDevTree: Sending DiSEqC Command: e0 31 6e d2 40 2008-02-15 18:15:31.465 DVBChan(5:5): Old Params: 12544760 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12457280 t_8psk a auto auto a a auto a r 2008-02-15 18:15:31.467 DVBChan(5:5): Tune(): Tuning to 1207280kHz 2008-02-15 18:15:31.483 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:15:31.485 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:15:31.486 DVBChan(5:5): SetChannelByString(9432): Tuned to frequency. 2008-02-15 18:15:31.487 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:15:31.489 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:15:32.552 TVRec(5): StartedRecording(0x891a5c8) fn(/shares/recordings/LiveTV/767_20080215181531.mpg) 2008-02-15 18:15:32.562 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:15:32.577 TVRec(5): FinishedRecording(Tom Petty and the Heartbreakers) in recgroup: LiveTV 2008-02-15 18:15:32.581 Chain: Updated endtime for '15470_20080215181511' to 20080215181532 2008-02-15 18:15:32.614 Finished recording Tom Petty and the Heartbreakers: channel 15470 2008-02-15 18:15:32.620 Preview: 'myth://192.168.1.121:6543/15470_20080215181511.mpg' is not local, replacing with '/shares/recordings/LiveTV/15470_20080215181511.mpg', which is local. 2008-02-15 18:15:32.633 Chain: Appended@13 '767_20080215181531' 2008-02-15 18:15:32.654 TVRec(5): Starting Signal Monitor 2008-02-15 18:15:32.659 TVRec(5): SetupSignalMonitor(1, 1) 2008-02-15 18:15:32.661 DVBChan(5:5): Opening DVB channel 2008-02-15 18:15:32.677 DVBSM(5): Can measure Signal Strength 2008-02-15 18:15:32.679 DVBSM(5): Can measure S/N 2008-02-15 18:15:32.681 DVBSM(5): Can measure Bit Error Rate 2008-02-15 18:15:32.682 DVBSM(5): Can count Uncorrected Blocks 2008-02-15 18:15:32.691 SM(5)::RemoveFlags: Seen() Match() Wait() 2008-02-15 18:15:32.692 DVBSM(5): DVBSignalMonitor::ctor initial flags Seen() Match() Wait(Sig,SNR,BER,UB,) 2008-02-15 18:15:32.694 TVRec(5): Signal monitor successfully created 2008-02-15 18:15:32.695 TVRec(5): Setting up table monitoring. 2008-02-15 18:15:32.711 Using profile 'Live TV' to record 2008-02-15 18:15:32.712 TVRec(5): DVB service_id 9432 on net_id 4102 tsid 217 2008-02-15 18:15:32.716 DTVSM(5)::SetDVBService(transport_id: 217, network_id: 4102, service_id: 9432): 2008-02-15 18:15:32.717 SM(5)::RemoveFlags: Seen(PMT,SDT,Crypt,) Match(PMT,SDT,Crypt,) Wait() 2008-02-15 18:15:32.719 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,) 2008-02-15 18:15:32.720 SM(5)::AddFlags: Seen() Match() Wait(PMT,NIT,Pos,) 2008-02-15 18:15:32.722 TVRec(5): Successfully set up DVB table monitoring. 2008-02-15 18:15:32.723 SM(5)::Start: begin 2008-02-15 18:15:32.725 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:15:32.727 SM(5)::Start: end 2008-02-15 18:15:32.728 DTVSM(5)::GetStatusList: WaitForPMT seen(0) matching(0) 2008-02-15 18:15:32.731 TVRec(5): SetFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:32.737 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:32.738 TVRec(5): SetFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,RecorderRunning, 2008-02-15 18:15:32.740 RecBase(5:5): SetRingBuffer(0) 2008-02-15 18:15:32.741 TVRec(5): SetFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING, 2008-02-15 18:15:32.742 DummyDTVRecorder -- started 2008-02-15 18:15:32.744 TVRec(5): SetFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:32.745 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:32.747 TVRec(5): SetFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,SignalMonitorRunning,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:32.748 TVRec(5): SetChannel(9432) -- end 2008-02-15 18:15:32.764 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:15:32.997 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:15:33.003 Empty LocalHostName. 2008-02-15 18:15:33.004 Using localhost value of corbin2 2008-02-15 18:15:33.060 New DB connection, total: 1 2008-02-15 18:15:33.073 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:33.078 Closing DB connection named 'DBManager0' 2008-02-15 18:15:33.081 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:33.088 Current Schema Version: 1208 2008-02-15 18:15:33.097 New DB connection, total: 2 2008-02-15 18:15:33.100 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:33.123 RingBuf(/shares/recordings/LiveTV/15470_20080215181511.mpg): Using io multiplier 5, chunk/max = 32768/655360 2008-02-15 18:15:33.179 [h264 @ 0xb727f188]non existing PPS referenced 2008-02-15 18:15:33.181 [h264 @ 0xb727f188]decode_slice_header error 2008-02-15 18:15:33.183 [h264 @ 0xb727f188]no frame! 2008-02-15 18:15:33.185 [h264 @ 0xb727f188]non existing PPS referenced 2008-02-15 18:15:33.186 [h264 @ 0xb727f188]decode_slice_header error 2008-02-15 18:15:33.187 [h264 @ 0xb727f188]no frame! 2008-02-15 18:15:33.194 [h264 @ 0xb727f188]non existing PPS referenced 2008-02-15 18:15:33.196 [h264 @ 0xb727f188]decode_slice_header error 2008-02-15 18:15:33.197 [h264 @ 0xb727f188]no frame! 2008-02-15 18:15:33.700 DVBSM(5): UpdateValues -- Signal Lost QString::setNum: Invalid format char '' 2008-02-15 18:15:35.682 AFD: Trying DirectShow for FOURCC 0x875967048 2008-02-15 18:15:35.689 AFD: Opened codec 0x82a1300, id(H264) type(Video) 2008-02-15 18:15:35.692 AFD: Opened codec 0x82a18f0, id(AC3) type(Audio) 2008-02-15 18:15:35.941 [h264 @ 0xb727f188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:35.977 [h264 @ 0xb727f188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:36.017 [h264 @ 0xb727f188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:36.023 [h264 @ 0xb727f188]error while decoding MB 22 9, bytestream (-3) 2008-02-15 18:15:36.046 [h264 @ 0xb727f188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:36.083 [h264 @ 0xb727f188]Interlaced pictures + spatial direct mode is not implemented 2008-02-15 18:15:36.174 Preview: Grabbed preview '/shares/recordings/LiveTV/15470_20080215181511.mpg' 1440x1088@69s 2008-02-15 18:15:42.696 DVBSH(5): Retuning for rotor completion 2008-02-15 18:15:42.698 DiSEqCDevTree: Changing LNB voltage to 13V 2008-02-15 18:15:42.701 DVBChan(5:5): Old Params: 12457280 t_8psk a auto auto a a auto a r DVBChan(5:5): New Params: 12457280 t_8psk a auto auto a a auto a r 2008-02-15 18:15:42.703 DVBChan(5:5): Tune(): Tuning to 1207280kHz 2008-02-15 18:15:42.718 dvbchannel.cpp:wait_for_backend: Status: 2008-02-15 18:15:42.720 DVBChan(5:5): Tune(): Frequency tuning successful. 2008-02-15 18:15:42.853 DVBSM(5): UpdateValues -- Signal Locked 2008-02-15 18:15:42.855 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:15:42.856 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:15:42.857 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:15:42.865 DVBSH(5): RunTS(): begin 2008-02-15 18:15:42.868 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:15:42.870 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:15:42.872 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:15:42.873 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:15:42.875 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:15:42.876 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:15:42.878 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:15:42.879 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:15:42.880 DVBSH(5): AddPIDFilter(0x1023) priority 3 2008-02-15 18:15:42.882 PIDInfo(5): Opening filter for pid 0x1023 2008-02-15 18:15:42.903 DVBRec(5:5): PID 0x1023 discontinuity detected 2008-02-15 18:15:42.920 DVBRec(5:5): SetPAT(9432 on 0x21) 2008-02-15 18:15:42.921 SM(5)::AddFlags: Seen(PAT,) Match() Wait() 2008-02-15 18:15:42.922 SM(5)::AddFlags: Seen() Match(PAT,) Wait() 2008-02-15 18:15:42.924 CreatePATSingleProgram() 2008-02-15 18:15:42.925 PAT in input stream 2008-02-15 18:15:42.926 Program Association Table PSIP tableID(0x0) length(41) extension(0xd9) version(19) current(1) section(0) last_section(0) tsid: 217 programCount: 8 program number 0 has PID 0x 10 data 0x 0 0x 0 0xe0 0x10 program number 535 has PID 0x 21 data 0x 2 0x17 0xe0 0x21 program number 536 has PID 0x 21 data 0x 2 0x18 0xe0 0x21 program number 537 has PID 0x 21 data 0x 2 0x19 0xe0 0x21 program number 540 has PID 0x 21 data 0x 2 0x1c 0xe0 0x21 program number 5904 has PID 0x 21 data 0x17 0x10 0xe0 0x21 program number 9431 has PID 0x 21 data 0x24 0xd7 0xe0 0x21 program number 9432 has PID 0x 21 data 0x24 0xd8 0xe0 0x21 2008-02-15 18:15:42.928 desired_program(9432) pid(0x21) 2008-02-15 18:15:42.929 pmt_pid(0x21) 2008-02-15 18:15:42.931 PAT for output stream 2008-02-15 18:15:42.932 Program Association Table PSIP tableID(0x0) length(13) extension(0xd9) version(19) current(1) section(0) last_section(0) tsid: 217 programCount: 1 program number 1 has PID 0x 21 data 0x 0 0x 1 0xe0 0x21 2008-02-15 18:15:42.933 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:15:42.935 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:15:42.950 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.951 DTVSM(5) Error: Wrong PMT; pmt->pn(540) desired(9432) 2008-02-15 18:15:42.953 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.954 DTVSM(5) Error: Wrong PMT; pmt->pn(536) desired(9432) 2008-02-15 18:15:42.968 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(0) 2008-02-15 18:15:42.969 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.973 DTVSM(5) Error: Wrong PMT; pmt->pn(535) desired(9432) 2008-02-15 18:15:42.975 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.976 DTVSM(5) Error: Wrong PMT; pmt->pn(537) desired(9432) 2008-02-15 18:15:42.978 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.979 DTVSM(5) Error: Wrong PMT; pmt->pn(5904) desired(9432) 2008-02-15 18:15:42.983 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.985 DTVSM(5) Error: Wrong PMT; pmt->pn(9431) desired(9432) 2008-02-15 18:15:42.987 DVBRec(5:5): SetPMT(9432) 2008-02-15 18:15:42.988 SM(5)::AddFlags: Seen(PMT,) Match() Wait() 2008-02-15 18:15:42.990 SM(5)::AddFlags: Seen() Match() Wait(Crypt,) 2008-02-15 18:15:42.991 SM(5)::AddFlags: Seen() Match(PMT,) Wait() 2008-02-15 18:15:42.992 CreatePMTSingleProgram() 2008-02-15 18:15:42.994 PMT in input stream 2008-02-15 18:15:42.995 Program Map Table ver(1) pid(0x21) pnum(9432) len(47) Stream #0 pid(0x1122) type(video-h264 0x1b) Conditional Access: sid(0x1816) pid(0x36) data_size(0) Conditional Access: sid(0x1810) pid(0x136) data_size(0) Conditional Access: sid(0x1801) pid(0x136) data_size(0) Stream #1 pid(0x1123) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:15:42.998 PMT for output stream 2008-02-15 18:15:42.999 Program Map Table ver(1) pid(0x21) pnum(1) len(29) Stream #0 pid(0x1122) type(video-h264 0x1b) Stream #1 pid(0x1123) type(audio-ac3 0x81) ISO-639 Language: code(eng) canonical(eng) eng(English) 2008-02-15 18:15:43.001 DVBSH(5): RemovePIDFilter(0x1023) 2008-02-15 18:15:43.003 PIDInfo(5): Closing filter for pid 0x1023 2008-02-15 18:15:43.004 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:15:43.006 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:15:43.008 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:15:43.009 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:15:43.023 DTVSM(5)::GetStatusList: WaitForPMT seen(1) matching(1) 2008-02-15 18:15:43.153 PID 0x1123 Found Payload Start 2008-02-15 18:15:43.219 DTVSM(5)::SetNIT(): net_id = 4102 2008-02-15 18:15:43.220 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:43.221 DTVSM(5)::setNIT(): nid = 4102 2008-02-15 18:15:43.223 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:43.341 DTVSM(5)::SetNIT(): net_id = 4102 2008-02-15 18:15:43.343 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:43.345 DTVSM(5)::setNIT(): nid = 4102 2008-02-15 18:15:43.346 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:43.444 DTVSM(5)::SetNIT(): net_id = 4102 2008-02-15 18:15:43.446 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:43.447 DTVSM(5)::setNIT(): nid = 4102 2008-02-15 18:15:43.448 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:43.694 PID 0x1122 status: Decrypted 2008-02-15 18:15:43.696 Program 9432 status: Decrypted 2008-02-15 18:15:43.698 SM(5)::AddFlags: Seen(Crypt,) Match() Wait() 2008-02-15 18:15:43.699 SM(5)::AddFlags: Seen() Match(Crypt,) Wait() 2008-02-15 18:15:43.701 PID 0x1122 Found Payload Start 2008-02-15 18:15:43.703 DTVSM(5)::SetNIT(): net_id = 4102 2008-02-15 18:15:43.704 SM(5)::AddFlags: Seen(NIT,) Match() Wait() 2008-02-15 18:15:43.705 DTVSM(5)::setNIT(): nid = 4102 2008-02-15 18:15:43.707 SM(5)::AddFlags: Seen() Match(NIT,) Wait() 2008-02-15 18:15:43.716 TVRec(5): Got good signal 2008-02-15 18:15:43.718 TVRec(5): TeardownSignalMonitor() -- begin 2008-02-15 18:15:43.719 DVBSM(5): Stop() -- begin 2008-02-15 18:15:43.720 SM(5)::Stop: begin 2008-02-15 18:15:43.769 SM(5)::Stop: end 2008-02-15 18:15:43.770 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:15:43.772 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:15:43.813 DVBSH(5): RunTS(): shutdown 2008-02-15 18:15:43.816 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:15:43.817 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:15:43.819 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:15:43.820 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:15:43.822 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:15:43.823 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:15:43.824 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:15:43.826 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:15:43.827 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:15:43.829 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:15:43.830 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:15:43.831 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:15:43.833 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:15:43.834 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:15:43.836 DVBSH(5): RunTS(): end 2008-02-15 18:15:43.838 DVBSH(5): RemoveListener(0xb133a170) -- end 2008-02-15 18:15:43.841 DVBSM(5): Stop() -- end 2008-02-15 18:15:43.842 DVBSM(5): Stop() -- begin 2008-02-15 18:15:43.843 SM(5)::Stop: begin 2008-02-15 18:15:43.845 SM(5)::Stop: end 2008-02-15 18:15:43.846 DVBSM(5): Stop() -- end 2008-02-15 18:15:43.847 TVRec(5): TeardownSignalMonitor() -- end 2008-02-15 18:15:43.847 DVBSM(5): Stop() -- begin 2008-02-15 18:15:43.849 TVRec(5): ClearFlags(SignalMonitorRunning,) -> RunMainLoop,CancelNextRecording,WaitingForSignal,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:43.851 SM(5)::Stop: begin 2008-02-15 18:15:43.853 TVRec(5): ClearFlags(WaitingForSignal,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,ANYRECRUNNING,RingBufferReady, 2008-02-15 18:15:43.855 SM(5)::Stop: end 2008-02-15 18:15:43.859 DVBSM(5): Stop() -- end 2008-02-15 18:15:43.860 SM(5)::Stop: begin 2008-02-15 18:15:43.862 TVRec(5): Restarting Recorder 2008-02-15 18:15:43.863 SM(5)::Stop: end 2008-02-15 18:15:43.865 TVRec(5): ClearFlags(DummyRecorderRunning,) -> RunMainLoop,CancelNextRecording,NeedToStartRecorder,RecorderRunning,RingBufferReady, 2008-02-15 18:15:43.875 TVRec(5): FinishedRecording(Stargate SG-1) in recgroup: LiveTV 2008-02-15 18:15:43.878 Chain: Updated endtime for '767_20080215181531' to 20080215181543 2008-02-15 18:15:43.884 Finished recording Stargate SG-1 "The Devil You Know": channel 767 2008-02-15 18:15:43.895 scheduler: Last message repeated 2 times: Finished recording: Tom Petty and the Heartbreakers: channel 15470 2008-02-15 18:15:43.905 scheduler: Finished recording: Stargate SG-1 "The Devil You Know": channel 767 2008-02-15 18:15:43.907 TVRec(5): SwitchLiveTVRingBuffer(discont 1, set_rec 0) 2008-02-15 18:15:43.908 TVRec(5): GetProgramRingBufferForLiveTV() 2008-02-15 18:15:44.967 TVRec(5): StartedRecording(0x8436688) fn(/shares/recordings/LiveTV/767_20080215181543.mpg) 2008-02-15 18:15:44.976 TFW: Using io multiplier 3, min/max = 16384/131072 2008-02-15 18:15:44.992 TVRec(5): FinishedRecording(Stargate SG-1) in recgroup: LiveTV 2008-02-15 18:15:44.995 Chain: Updated endtime for '767_20080215181531' to 20080215181544 2008-02-15 18:15:45.030 Finished recording Stargate SG-1 "The Devil You Know": channel 767 2008-02-15 18:15:45.035 Preview: 'myth://192.168.1.121:6543/767_20080215181531.mpg' is not local, replacing with '/shares/recordings/LiveTV/767_20080215181531.mpg', which is local. 2008-02-15 18:15:45.050 Chain: Appended@14 '767_20080215181543' 2008-02-15 18:15:45.054 RecBase(5:5): SetRingBuffer(0xb1234bb8) '/shares/recordings/LiveTV/767_20080215181543.mpg' 2008-02-15 18:15:45.064 RecBase(5:5): SetRecording(0xb1249440) title(Stargate SG-1) 2008-02-15 18:15:45.069 DTVRec(5): Reset(void) 2008-02-15 18:15:45.071 DTVRec(5): ResetForNewFile(void) 2008-02-15 18:15:45.074 TVRec(5): ClearFlags(NeedToStartRecorder,) -> RunMainLoop,CancelNextRecording,RecorderRunning,RingBufferReady, 2008-02-15 18:15:45.074 DVBSH(5): AddListener(0xb133a170) -- begin 2008-02-15 18:15:45.079 DVBSH(5): AddListener(0xb133a170) -- locked 2008-02-15 18:15:45.081 DVBSH(5): AddListener(0xb133a170) -- end 2008-02-15 18:15:45.160 AutoExpire: CalcParams(): Max required Free Space: 7.0 GB w/freq: 15 min 2008-02-15 18:15:45.286 DevRdB(5): buffer size 65800 KB 2008-02-15 18:15:45.289 DVBSH(5): RunTS(): begin 2008-02-15 18:15:45.291 DVBSH(5): AddPIDFilter(0x0) priority 2 2008-02-15 18:15:45.292 PIDInfo(5): Opening filter for pid 0x0 2008-02-15 18:15:45.294 DVBSH(5): AddPIDFilter(0x10) priority 2 2008-02-15 18:15:45.296 PIDInfo(5): Opening filter for pid 0x10 2008-02-15 18:15:45.297 DVBSH(5): AddPIDFilter(0x11) priority 2 2008-02-15 18:15:45.299 PIDInfo(5): Opening filter for pid 0x11 2008-02-15 18:15:45.301 DVBSH(5): AddPIDFilter(0x14) priority 2 2008-02-15 18:15:45.302 PIDInfo(5): Opening filter for pid 0x14 2008-02-15 18:15:45.304 DVBSH(5): AddPIDFilter(0x21) priority 2 2008-02-15 18:15:45.306 PIDInfo(5): Opening filter for pid 0x21 2008-02-15 18:15:45.307 DVBSH(5): AddPIDFilter(0x1122) priority 3 2008-02-15 18:15:45.309 PIDInfo(5): Opening filter for pid 0x1122 2008-02-15 18:15:45.311 DVBSH(5): AddPIDFilter(0x1123) priority 3 2008-02-15 18:15:45.313 PIDInfo(5): Opening filter for pid 0x1123 2008-02-15 18:15:45.370 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x10, continuity counter: 14 (expected 13). 2008-02-15 18:15:45.372 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x11, continuity counter: 15 (expected 7). 2008-02-15 18:15:45.374 AddTSPacket: Out of sync!!! Need to wait for next payloadStart PID: 0x21, continuity counter: 15 (expected 14). 2008-02-15 18:15:45.375 PID 0x1122 Found Payload Start 2008-02-15 18:15:45.425 PID 0x1123 Found Payload Start 2008-02-15 18:15:45.431 Using runtime prefix = /opt/mns/mythtv.15682, libdir = /opt/mns/mythtv.15682/lib 2008-02-15 18:15:45.435 Empty LocalHostName. 2008-02-15 18:15:45.437 Using localhost value of corbin2 2008-02-15 18:15:45.495 New DB connection, total: 1 2008-02-15 18:15:45.508 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:45.513 Closing DB connection named 'DBManager0' 2008-02-15 18:15:45.516 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:45.521 Current Schema Version: 1208 2008-02-15 18:15:45.529 New DB connection, total: 2 2008-02-15 18:15:45.532 Connected to database 'mythconverg' at host: corbin2 2008-02-15 18:15:45.550 Preview Error: Previewer file '/shares/recordings/LiveTV/767_20080215181531.mpg' is not valid. 2008-02-15 18:15:45.555 Preview Error: Run() file not local: '/shares/recordings/LiveTV/767_20080215181531.mpg' 2008-02-15 18:15:45.569 Preview Error: Preview process not ok. fileinfo(/shares/recordings/LiveTV/767_20080215181531.mpg.png) exits: 0 readable: 0 size: 0 2008-02-15 18:15:45.805 DVBRec(5:5): PID 0x1122 discontinuity detected 2008-02-15 18:16:02.641 TVRec(5): Found channel (9462) on current card(5). 2008-02-15 18:16:02.647 ChannelBase(5): Failed to find channel(9462) on current input (DiSEqC v1.3 Input 1) of card (5). 2008-02-15 18:16:02.651 ChannelBase(5): Found channel(9462) on another input (DiSEqC v1.3 Input 5) of card (5). 2008-02-15 18:16:02.656 TVRec(5): SetChannel(9462) -- begin 2008-02-15 18:16:02.657 TVRec(5): ClearFlags(RingBufferReady,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:16:02.659 TVRec(5): Request: Program(no) channel(9462) input() flags(LiveTV,) 2008-02-15 18:16:02.662 ChannelBase(5): Looking for startchannel '9462' on input 'DiSEqC v1.3 Input 1' 2008-02-15 18:16:02.663 DVBSH(5): RemoveListener(0xb133a170) -- begin 2008-02-15 18:16:02.666 DVBSH(5): RemoveListener(0xb133a170) -- locked 2008-02-15 18:16:02.667 DVBSH(5): RemovePIDFilter(0x0) 2008-02-15 18:16:02.670 PIDInfo(5): Closing filter for pid 0x0 2008-02-15 18:16:02.672 DVBSH(5): RemovePIDFilter(0x10) 2008-02-15 18:16:02.673 PIDInfo(5): Closing filter for pid 0x10 2008-02-15 18:16:02.675 DVBSH(5): RemovePIDFilter(0x11) 2008-02-15 18:16:02.677 PIDInfo(5): Closing filter for pid 0x11 2008-02-15 18:16:02.678 DVBSH(5): RemovePIDFilter(0x14) 2008-02-15 18:16:02.680 PIDInfo(5): Closing filter for pid 0x14 2008-02-15 18:16:02.682 DVBSH(5): RemovePIDFilter(0x21) 2008-02-15 18:16:02.683 PIDInfo(5): Closing filter for pid 0x21 2008-02-15 18:16:02.685 DVBSH(5): RemovePIDFilter(0x1122) 2008-02-15 18:16:02.687 PIDInfo(5): Closing filter for pid 0x1122 2008-02-15 18:16:02.689 DVBSH(5): RemovePIDFilter(0x1123) 2008-02-15 18:16:02.691 PIDInfo(5): Closing filter for pid 0x1123 2008-02-15 18:16:02.698 ChannelBase(5): Found startchannel '9462' on input 'DiSEqC v1.3 Input 5' 2008-02-15 18:16:02.700 TVRec(5): HW Tuner: 5->5 2008-02-15 18:16:02.702 TVRec(5): ClearFlags(PENDINGACTIONS,) -> RunMainLoop,CancelNextRecording,RecorderRunning, 2008-02-15 18:16:02.703 TVRec(5): Waiting for recorder pause.. 2008-02-15 18:16:02.704 TVRec(5): SetFlags(WaitingForRecPause,) -> RunMainLoop,CancelNextRecording,WaitingForRecPause,RecorderRunning, 2008-02-15 18:16:57.533 Expiring 28 MBytes for 486 @ Fri Feb 15 18:12:48 2008 => Unknown 2008-02-15 18:16:57.560 autoexpire: Expiring Program: Expiring 28 MBytes for 486 @ Fri Feb 15 18:12:48 2008 => Unknown 2008-02-15 18:16:57.562 Expiring 0 MBytes for 15480 @ Fri Feb 15 18:10:00 2008 => Strange Case of Sherlock Holmes &... 2008-02-15 18:16:57.577 autoexpire: Expiring Program: Expiring 0 MBytes for 15480 @ Fri Feb 15 18:10:00 2008 => Strange Case of Sherlock Holmes &... 2008-02-15 18:16:57.593 Expiring 8 MBytes for 15480 @ Fri Feb 15 18:10:00 2008 => Strange Case of Sherlock Holmes &... 2008-02-15 18:16:57.611 autoexpire: Expiring Program: Expiring 8 MBytes for 15480 @ Fri Feb 15 18:10:00 2008 => Strange Case of Sherlock Holmes &... 2008-02-15 18:16:57.614 Expiring 0 MBytes for 15471 @ Fri Feb 15 18:00:00 2008 => Penny Revolution 2008-02-15 18:16:57.629 autoexpire: Expiring Program: Expiring 0 MBytes for 15471 @ Fri Feb 15 18:00:00 2008 => Penny Revolution 2008-02-15 18:16:57.631 Expiring 8 MBytes for 15471 @ Fri Feb 15 18:00:00 2008 => Penny Revolution 2008-02-15 18:16:57.652 autoexpire: Expiring Program: Expiring 8 MBytes for 15471 @ Fri Feb 15 18:00:00 2008 => Penny Revolution 2008-02-15 18:16:57.654 Expiring 0 MBytes for 15472 @ Fri Feb 15 18:00:00 2008 => Artstar 2008-02-15 18:16:57.681 autoexpire: Expiring Program: Expiring 0 MBytes for 15472 @ Fri Feb 15 18:00:00 2008 => Artstar 2008-02-15 18:16:57.684 Expiring 14 MBytes for 15472 @ Fri Feb 15 18:00:00 2008 => Artstar 2008-02-15 18:16:57.704 autoexpire: Expiring Program: Expiring 14 MBytes for 15472 @ Fri Feb 15 18:00:00 2008 => Artstar 2008-02-15 18:16:57.706 Expiring 0 MBytes for 224 @ Fri Feb 15 18:00:00 2008 => Boost Mobile Rock Corps L.A. '07 2008-02-15 18:16:57.720 autoexpire: Expiring Program: Expiring 0 MBytes for 224 @ Fri Feb 15 18:00:00 2008 => Boost Mobile Rock Corps L.A. '07 2008-02-15 18:16:57.723 Expiring 4 MBytes for 224 @ Fri Feb 15 18:00:00 2008 => Boost Mobile Rock Corps L.A. '07 2008-02-15 18:16:57.739 autoexpire: Expiring Program: Expiring 4 MBytes for 224 @ Fri Feb 15 18:00:00 2008 => Boost Mobile Rock Corps L.A. '07 2008-02-15 18:16:57.742 Expiring 0 MBytes for 15470 @ Fri Feb 15 18:00:00 2008 => Tom Petty and the Heartbreakers 2008-02-15 18:16:57.755 autoexpire: Expiring Program: Expiring 0 MBytes for 15470 @ Fri Feb 15 18:00:00 2008 => Tom Petty and the Heartbreakers 2008-02-15 18:19:15.680 Unknown socket closing 2008-02-15 18:19:15.691 MythSocket(b124b8d0:-1): writeStringList: Error, socket went unconnected. 2008-02-15 18:19:15.748 Unknown socket closing 2008-02-15 18:19:15.784 MythSocket(b14aa978:-1): writeStringList: Error, socket went unconnected. 2008-02-15 18:19:15.852 MythSocket(b130e3a0:-1): writeStringList: Error, socket went unconnected. 2008-02-15 18:19:18.477 MainServer::HandleAnnounce Monitor 2008-02-15 18:19:18.478 adding: pc4 as a client (events: 0) 2008-02-15 18:19:54.208 MainServer::HandleAnnounce Monitor 2008-02-15 18:19:54.212 adding: pc4 as a client (events: 0) 2008-02-15 18:20:18.082 MythSocket(b12134c0:-1): writeStringList: Error, socket went unconnected. 2008-02-15 18:20:21.291 Expiring 9 MBytes for 15470 @ Fri Feb 15 18:00:00 2008 => Tom Petty and the Heartbreakers 2008-02-15 18:20:21.901 autoexpire: Expiring Program: Expiring 9 MBytes for 15470 @ Fri Feb 15 18:00:00 2008 => Tom Petty and the Heartbreakers 2008-02-15 18:20:21.904 Expiring 0 MBytes for 767 @ Fri Feb 15 18:00:00 2008 => Stargate SG-1 "The Devil You Know" 2008-02-15 18:20:21.971 autoexpire: Expiring Program: Expiring 0 MBytes for 767 @ Fri Feb 15 18:00:00 2008 => Stargate SG-1 "The Devil You Know"