Added in device.c debug output to cDevice::GetDevice(const cChannel *Channel, int Priority, bool LiveView) :
GetDevice 2 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 2 0 1 -1 no usable CAM slots! GetDevice 2 0 1 -1 no usable CAM slots! GetDevice 2 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 2 0 1 -1 no usable CAM slots! GetDevice 2 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 1 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 1 0 1 -1 no usable CAM slots! GetDevice 1 0 1 -1 no usable CAM slots! GetDevice 1 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 2 0 1 -1 j = 1, i = 0, imp = 062C4C5A, Impact = FFFFFFFF device 0 GetDevice 2 0 1 -1 j = 1, i = 0, imp = 020C4C4A, Impact = FFFFFFFF device 0 GetDevice 3 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 3 0 1 -1 no usable CAM slots! GetDevice 3 0 1 -1 no usable CAM slots! GetDevice 3 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 3 0 1 -1 no usable CAM slots! GetDevice 4 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 4 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 5 0 1 -1 j = 0, i = 0, imp = 062C4C7E, Impact = FFFFFFFF device 0 GetDevice 5 0 1 -1 j = 0, i = 0, imp = 020C4C6E, Impact = FFFFFFFF device 0 GetDevice 6 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 6 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 6 0 1 -1 no usable CAM slots! GetDevice 7 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 7 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 7 0 1 -1 no usable CAM slots! GetDevice 8 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 8 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 8 0 1 -1 no usable CAM slots! GetDevice 9 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 9 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 9 0 1 -1 no usable CAM slots! GetDevice 9 0 1 -1 no usable CAM slots! GetDevice 10 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 10 0 1 -1 j = 1, i = 0, imp = 020C4C4B, Impact = FFFFFFFF device 0 GetDevice 10 0 1 -1 no usable CAM slots! GetDevice 10 0 1 -1 no usable CAM slots!
and syslog gives :
May 4 10:20:40 localhost vdr: [645] TS buffer on device 1 thread started (pid=390, tid=645) May 4 10:20:43 localhost vdr: [643] transfer thread ended (pid=390, tid=643) May 4 10:20:43 localhost vdr: [645] TS buffer on device 1 thread ended (pid=390, tid=645) May 4 10:20:43 localhost vdr: [644] buffer stats: 92308 (4%) used May 4 10:20:43 localhost vdr: [644] receiver on device 1 thread ended (pid=390, tid=644) May 4 10:20:50 localhost vdr: [390] switching to channel 3 May 4 10:20:50 localhost vdr: [390] buffer stats: 66364 (3%) used May 4 10:20:50 localhost vdr: [390] info: Channel not available! May 4 10:20:58 localhost vdr: [390] switching to channel 4 May 4 10:20:58 localhost vdr: [655] transfer thread started (pid=390, tid=655) May 4 10:20:58 localhost vdr: [656] receiver on device 1 thread started (pid=390, tid=656) May 4 10:20:58 localhost vdr: [657] TS buffer on device 1 thread started (pid=390, tid=657) May 4 10:20:58 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:20:58 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:01 localhost vdr: [655] transfer thread ended (pid=390, tid=655) May 4 10:21:01 localhost vdr: [390] CAM 2: unassigned May 4 10:21:01 localhost vdr: [390] switching to channel 5 May 4 10:21:01 localhost vdr: [390] buffer stats: 64484 (3%) used May 4 10:21:02 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:21:02 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:02 localhost vdr: [657] TS buffer on device 1 thread ended (pid=390, tid=657) May 4 10:21:02 localhost vdr: [656] buffer stats: 64108 (3%) used May 4 10:21:02 localhost vdr: [656] receiver on device 1 thread ended (pid=390, tid=656) May 4 10:21:04 localhost vdr: [390] CAM 2: assigned to device 1 May 4 10:21:04 localhost vdr: [390] switching to channel 6 May 4 10:21:04 localhost vdr: [667] transfer thread started (pid=390, tid=667) May 4 10:21:04 localhost vdr: [668] receiver on device 1 thread started (pid=390, tid=668) May 4 10:21:04 localhost vdr: [669] TS buffer on device 1 thread started (pid=390, tid=669) May 4 10:21:04 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:21:04 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:08 localhost vdr: [667] transfer thread ended (pid=390, tid=667) May 4 10:21:08 localhost vdr: [669] TS buffer on device 1 thread ended (pid=390, tid=669) May 4 10:21:08 localhost vdr: [668] buffer stats: 97384 (4%) used May 4 10:21:08 localhost vdr: [668] receiver on device 1 thread ended (pid=390, tid=668) May 4 10:21:09 localhost vdr: [390] switching to channel 6 May 4 10:21:09 localhost vdr: [390] buffer stats: 33088 (1%) used May 4 10:21:09 localhost vdr: [390] info: Channel not available! May 4 10:21:20 localhost vdr: [390] switching to channel 7 May 4 10:21:20 localhost vdr: [687] transfer thread started (pid=390, tid=687) May 4 10:21:20 localhost vdr: [688] receiver on device 1 thread started (pid=390, tid=688) May 4 10:21:20 localhost vdr: [689] TS buffer on device 1 thread started (pid=390, tid=689) May 4 10:21:20 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:21:20 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:24 localhost vdr: [687] transfer thread ended (pid=390, tid=687) May 4 10:21:24 localhost vdr: [689] TS buffer on device 1 thread ended (pid=390, tid=689) May 4 10:21:24 localhost vdr: [688] buffer stats: 138180 (6%) used May 4 10:21:24 localhost vdr: [688] receiver on device 1 thread ended (pid=390, tid=688) May 4 10:21:25 localhost vdr: [390] switching to channel 7 May 4 10:21:25 localhost vdr: [390] buffer stats: 50384 (2%) used May 4 10:21:25 localhost vdr: [390] info: Channel not available! May 4 10:21:31 localhost vdr: [390] switching to channel 8 May 4 10:21:31 localhost vdr: [699] transfer thread started (pid=390, tid=699) May 4 10:21:31 localhost vdr: [700] receiver on device 1 thread started (pid=390, tid=700) May 4 10:21:31 localhost vdr: [701] TS buffer on device 1 thread started (pid=390, tid=701) May 4 10:21:31 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:21:31 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:33 localhost vdr: [396] channel 23 (C CINEMA PREMIER) event Sun 04.05.2008 08:05-10:45 'L'ENFER DU DIMANCHE' status 4 May 4 10:21:35 localhost vdr: [699] transfer thread ended (pid=390, tid=699) May 4 10:21:35 localhost vdr: [701] TS buffer on device 1 thread ended (pid=390, tid=701) May 4 10:21:35 localhost vdr: [700] buffer stats: 110356 (5%) used May 4 10:21:35 localhost vdr: [700] receiver on device 1 thread ended (pid=390, tid=700) May 4 10:21:36 localhost vdr: [390] switching to channel 8 May 4 10:21:36 localhost vdr: [390] buffer stats: 44180 (2%) used May 4 10:21:36 localhost vdr: [390] info: Channel not available! May 4 10:21:42 localhost vdr: [390] switching to channel 9 May 4 10:21:42 localhost vdr: [710] transfer thread started (pid=390, tid=710) May 4 10:21:42 localhost vdr: [711] receiver on device 1 thread started (pid=390, tid=711) May 4 10:21:42 localhost vdr: [712] TS buffer on device 1 thread started (pid=390, tid=712) May 4 10:21:42 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:21:42 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:21:46 localhost vdr: [710] transfer thread ended (pid=390, tid=710) May 4 10:21:46 localhost vdr: [712] TS buffer on device 1 thread ended (pid=390, tid=712) May 4 10:21:46 localhost vdr: [711] buffer stats: 87984 (4%) used May 4 10:21:46 localhost vdr: [711] receiver on device 1 thread ended (pid=390, tid=711) May 4 10:21:47 localhost vdr: [390] switching to channel 9 May 4 10:21:47 localhost vdr: [390] buffer stats: 33840 (1%) used May 4 10:21:47 localhost vdr: [390] info: Channel not available! May 4 10:21:58 localhost vdr: [390] switching to channel 9 May 4 10:21:58 localhost vdr: [390] info: Channel not available! May 4 10:22:02 localhost vdr: [390] switching to channel 10 May 4 10:22:02 localhost vdr: [731] transfer thread started (pid=390, tid=731) May 4 10:22:02 localhost vdr: [732] receiver on device 1 thread started (pid=390, tid=732) May 4 10:22:02 localhost vdr: [733] TS buffer on device 1 thread started (pid=390, tid=733) May 4 10:22:02 localhost kernel: dvb_frontend_ioctl: DVBFE_GET_INFO May 4 10:22:02 localhost kernel: dvb_frontend_ioctl: FESTATE_RETUNE: fepriv->state=2 May 4 10:22:03 localhost vdr: [396] channel 46 (SERIE CLUB) event Sun 04.05.2008 10:05-11:37 'LES BOEUF-CAROTTES' status 4 May 4 10:22:06 localhost vdr: [731] transfer thread ended (pid=390, tid=731) May 4 10:22:06 localhost vdr: [733] TS buffer on device 1 thread ended (pid=390, tid=733) May 4 10:22:06 localhost vdr: [732] buffer stats: 113740 (5%) used May 4 10:22:06 localhost vdr: [732] receiver on device 1 thread ended (pid=390, tid=732) May 4 10:22:09 localhost vdr: [390] switching to channel 10 May 4 10:22:09 localhost vdr: [390] buffer stats: 53204 (2%) used May 4 10:22:09 localhost vdr: [390] info: Channel not available! May 4 10:22:09 localhost vdr: [390] retuning due to modification of channel 10 May 4 10:22:09 localhost vdr: [390] switching to channel 10 May 4 10:22:09 localhost vdr: [390] info: Channel not available! May 4 10:22:20 localhost vdr: [390] confirm: CAM is in use - really reset? May 4 10:22:20 localhost vdr: [390] warning: CAM is in use - really reset? May 4 10:22:23 localhost vdr: [390] confirmed May 4 10:22:23 localhost vdr: [394] CAM 2: module reset May 4 10:22:23 localhost vdr: [394] CAM 2: module present May 4 10:22:24 localhost vdr: [394] CAM 2: module ready May 4 10:22:29 localhost vdr: [390] confirm: CAM is in use - really reset? May 4 10:22:29 localhost vdr: [390] warning: CAM is in use - really reset? May 4 10:22:31 localhost vdr: [390] confirmed May 4 10:22:32 localhost vdr: [394] CAM 2: module present May 4 10:22:33 localhost vdr: [394] CAM 2: module ready May 4 10:22:36 localhost vdr: [390] ERROR: Can't open CAM menu!
Pierre