21:23:26 T:139800589981760 NOTICE: Log level changed to "LOG_LEVEL_DEBUG_FREEMEM" 21:23:26 T:139800589981760 NOTICE: Enabled debug logging due to GUI setting. Level 2. 21:23:26 T:139800589981760 DEBUG: ------ Window Init () ------ 21:23:26 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 74 21:23:26 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:26 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:26 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:23:28 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:28 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:28 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:23:30 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:23:30 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:23:30 T:139800589981760 DEBUG: ------ Window Init (DialogSelect.xml) ------ 21:23:30 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 74 21:23:34 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:34 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:34 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 72 21:23:34 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:23:34 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:23:35 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 76 21:23:35 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:35 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:35 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 73 21:23:36 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:36 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:36 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 70 21:23:38 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:38 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:38 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:23:38 T:139799007069952 DEBUG: Thread JobWorker 139799007069952 terminating (autodelete) 21:23:39 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:39 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:39 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:23:39 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:39 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:39 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:40 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 68 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:40 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:40 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:40 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:23:41 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:41 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:41 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:23:41 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:41 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:41 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 164 21:23:41 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:41 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:42 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 430 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:42 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 69 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:42 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:42 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:23:42 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:42 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:43 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:23:44 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:44 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:44 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 80 21:23:44 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:23:44 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:23:44 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 207 21:23:45 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:45 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:45 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:23:45 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:45 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:46 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 73 21:23:46 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:46 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:46 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 69 21:23:46 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:23:46 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:23:46 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 69 21:23:47 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:23:47 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:23:47 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 74 21:23:48 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:23:48 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:23:48 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 75 21:23:52 T:139800589981760 DEBUG: SECTION:UnloadDelayed(DLL: special://xbmcbin/system/ImageLib-x86_64-linux.so) 21:23:52 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 0 21:23:52 T:139800589981760 DEBUG: OnKey: 169 (0xa9) pressed, action is Left 21:23:52 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 74 21:23:53 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:23:53 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:23:53 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:23:53 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:23:53 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:23:53 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 74 21:23:54 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:23:54 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:23:54 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 74 21:23:55 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:23:55 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:23:55 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 79 21:23:56 T:139799138645760 NOTICE: ES: Client from 127.0.0.1 timed out 21:23:58 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:23:58 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:23:58 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 75 21:23:58 T:139800589981760 DEBUG: ------ Window Deinit (DialogSelect.xml) ------ 21:24:00 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:24:00 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:6734c60 21:24:00 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:24:00 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:24:00 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:24:00 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:24:00 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17090.142 21:24:00 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:00 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:00 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:24:00 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:00 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:73ms 21:24:00 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 73 21:24:00 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:01 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:01 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:01 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17090.794 21:24:01 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:01 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:75ms 21:24:01 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:24:01 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:6734c60 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:24:01 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:24:01 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17091.482 21:24:01 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:01 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:01 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:75ms 21:24:01 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:24:01 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:24:02 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:6734c60 21:24:02 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:24:02 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:24:02 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:24:02 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17092.051 21:24:02 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:02 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:02 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:79ms 21:24:02 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 79 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:02 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:02 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:02 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:02 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:02 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17092.699 21:24:02 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:02 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:02 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:03 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(1) duration(0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:03 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17092.960 21:24:03 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:2,rel:0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:03 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:335ms 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 335 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:03 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:03 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17093.232 21:24:03 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:03 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:74ms 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 74 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:03 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:03 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17093.466 21:24:03 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:03 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:03 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:75ms 21:24:03 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:24:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:04 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:24:04 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:6734c60 21:24:04 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:24:04 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:24:04 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:24:04 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:24:04 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17094.373 21:24:04 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:04 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:04 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:24:04 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:04 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:75ms 21:24:04 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:24:04 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:05 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:05 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:05 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17095.236 21:24:05 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:05 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:74ms 21:24:05 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 74 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:05 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:05 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:05 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17095.574 21:24:05 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:05 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:05 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:69ms 21:24:05 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 69 21:24:05 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:06 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:0d 21:24:06 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton exit (d) D:0ms cur:6734c60 21:24:06 T:139800151701248 DEBUG: CecLogMessage - key pressed: exit (d) current(ff) duration(0) 21:24:06 T:139800151701248 DEBUG: CecLogMessage - Changed key exit (d) D:0ms cur:ff 21:24:06 T:139800151701248 DEBUG: CecLogMessage - key pressed: exit (d, 0) 21:24:06 T:139800151701248 DEBUG: PushCecKeypress - received key d8 duration 0 21:24:06 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17096.578 21:24:06 T:139800151701248 DEBUG: CecLogMessage - Key exit: idle (duration:0) (d) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:06 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:06 T:139800589981760 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 21:24:06 T:139800589981760 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:24:06 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:06 T:139800151701248 DEBUG: CecLogMessage - key released: exit (d) D:69ms 21:24:06 T:139800151701248 DEBUG: PushCecKeypress - received key d8 duration 69 21:24:06 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:07 T:139800589981760 DEBUG: ------ Window Deinit (SettingsCategory.xml) ------ 21:24:07 T:139800589981760 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:24:07 T:139800589981760 DEBUG: ------ Window Init (Settings.xml) ------ 21:24:07 T:139799007069952 DEBUG: Thread JobWorker start, auto delete: true 21:24:07 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:0d 21:24:07 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton exit (d) D:0ms cur:6734c60 21:24:07 T:139800151701248 DEBUG: CecLogMessage - key pressed: exit (d) current(ff) duration(0) 21:24:07 T:139800151701248 DEBUG: CecLogMessage - Changed key exit (d) D:0ms cur:ff 21:24:07 T:139800151701248 DEBUG: CecLogMessage - key pressed: exit (d, 0) 21:24:07 T:139800151701248 DEBUG: PushCecKeypress - received key d8 duration 0 21:24:07 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17097.574 21:24:07 T:139800151701248 DEBUG: CecLogMessage - Key exit: idle (duration:0) (d) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:07 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:07 T:139800589981760 DEBUG: OnKey: menu (0xd8) pressed, action is PreviousMenu 21:24:07 T:139800589981760 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:24:07 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:07 T:139800151701248 DEBUG: CecLogMessage - key released: exit (d) D:78ms 21:24:07 T:139800151701248 DEBUG: PushCecKeypress - received key d8 duration 78 21:24:07 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:08 T:139800589981760 DEBUG: ------ Window Deinit (Settings.xml) ------ 21:24:08 T:139800589981760 DEBUG: CGUIWindowManager::PreviousWindow: Activate new 21:24:08 T:139800589981760 DEBUG: ------ Window Init (Home.xml) ------ 21:24:08 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:24:08 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:6734c60 21:24:08 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:24:08 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:24:08 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:24:08 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:24:08 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17098.539 21:24:08 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:08 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:08 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:24:08 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:08 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:73ms 21:24:08 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 73 21:24:08 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:09 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:24:09 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:6734c60 21:24:09 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:24:09 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:24:09 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:24:09 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:24:09 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17099.529 21:24:09 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:09 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:09 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:24:09 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:09 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:69ms 21:24:09 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 69 21:24:09 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:13 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:24:13 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:24:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:03 21:24:16 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton left (3) D:0ms cur:6734c60 21:24:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3) current(ff) duration(0) 21:24:16 T:139800151701248 DEBUG: CecLogMessage - Changed key left (3) D:0ms cur:ff 21:24:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3, 0) 21:24:16 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 0 21:24:16 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17106.509 21:24:16 T:139800151701248 DEBUG: CecLogMessage - Key left: idle (duration:0) (3) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:16 T:139800589981760 DEBUG: OnKey: 169 (0xa9) pressed, action is Left 21:24:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:16 T:139800151701248 DEBUG: CecLogMessage - key released: left (3) D:75ms 21:24:16 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 75 21:24:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:17 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:04 21:24:17 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:6734c60 21:24:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 21:24:17 T:139800151701248 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 21:24:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4, 0) 21:24:17 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:24:17 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17107.508 21:24:17 T:139800151701248 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:17 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:17 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:24:17 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:17 T:139800151701248 DEBUG: CecLogMessage - key released: right (4) D:71ms 21:24:17 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 71 21:24:17 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:21 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:24:21 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:24:23 T:139800151701248 DEBUG: CecLogMessage - >> 50:8c 21:24:23 T:139800151701248 DEBUG: CecLogMessage - >> 0f:87:00:80:45 21:24:23 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Broadcast (F): vendor id Panasonic (8045) 21:24:23 T:139800151701248 DEBUG: CecLogMessage - << 8f:87:00:80:45 21:24:23 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> 50:9f 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> 0f:a0:00:80:45:20:01:11 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): vendor command with id (A0) 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> 0f:36 21:24:24 T:139800151701248 DEBUG: CecLogMessage - TV (0): power status changed from 'on' to 'standby' 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): standby (36) 21:24:24 T:139800589981760 DEBUG: LogindUPowerSyscall: Received PrepareForSleep with arg 1 21:24:24 T:139800589981760 DEBUG: CAnnouncementManager - Announcement: OnSleep from xbmc 21:24:24 T:139800589981760 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnSleep 21:24:24 T:139800589981760 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=System, sender=xbmc, message=OnSleep) 21:24:24 T:139799600015104 DEBUG: Thread CECAdapterUpdate 139799600015104 terminating 21:24:24 T:139800011060992 DEBUG: Process - sending standby commands 21:24:24 T:139800151701248 DEBUG: CecLogMessage - << putting 'TV' (0) in standby mode 21:24:24 T:139800151701248 DEBUG: CecLogMessage - << 80:36 21:24:24 T:139800151701248 DEBUG: CecLogMessage - >> 05:9e:05 21:24:24 T:139800151701248 DEBUG: CecLogMessage - TV (0): CEC version 1.4 21:24:24 T:139800151701248 DEBUG: CecLogMessage - << putting 'Audio' (5) in standby mode 21:24:24 T:139800151701248 DEBUG: CecLogMessage - << 85:36 21:24:24 T:139800151701248 DEBUG: CecLogMessage - unregistering all CEC clients 21:24:24 T:139800151701248 DEBUG: CecLogMessage - unregistering client: libCEC version = 3.0.1, client version = 3.0.0, firmware version = 4, firmware build date: Thu Dec 6 11:15:20 2012 +0000, logical address(es) = Playback 2 (8) , physical address: 1.2.0.0, git revision: f51b881 , compiled on Tue May 17 09:25:36 UTC 2016 by chewitt@buildbox on Linux 4.4.0-17-generic (x86_64), features: P8_USB, P8_detect, randr 21:24:24 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): power status changed from 'on' to 'unknown' 21:24:24 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): vendor = Unknown (000000) 21:24:24 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): CEC version unknown 21:24:24 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): osd name set to 'Playback 2' 21:24:24 T:139800151701248 DEBUG: CecLogMessage - marking Playback 2 (8) as inactive source 21:24:24 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): device status changed into 'unknown' 21:24:24 T:139800151701248 DEBUG: CecLogMessage - setting ackmask to 0 21:24:24 T:139800151701248 DEBUG: CecLogMessage - turning controlled mode off 21:24:24 T:139800151701248 DEBUG: CecLogMessage - unregistering all CEC clients 21:24:25 T:139800151701248 DEBUG: CecLogMessage - communication thread ended 21:24:25 T:139800011060992 DEBUG: Process - CEC adapter processor thread ended 21:24:25 T:139800011060992 DEBUG: Thread CECAdapter 139800011060992 terminating 21:24:25 T:139800589981760 DEBUG: ------ Window Init (DialogBusy.xml) ------ 21:24:25 T:139800589981760 NOTICE: OnSleep: Running sleep jobs 21:24:25 T:139800589981760 NOTICE: OnSleep: Stopping lirc 21:24:25 T:139800589981760 INFO: LIRC SetEnabled: disabled 21:24:25 T:139800589981760 DEBUG: CApplication::CloseNetworkShares: Closing all network shares 21:24:25 T:139800589981760 DEBUG: LogindUPowerSyscall - delay lock released 21:24:25 T:139800589981760 ERROR: Keymapping error: no such action 'lirc.stop' defined 21:24:50 T:139800511342336 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 21:24:50 T:139800002668288 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 21:24:50 T:139800589981760 ERROR: Keymapping error: no such action 'lirc.start' defined 21:24:50 T:139800589981760 DEBUG: LogindUPowerSyscall: Received PrepareForSleep with arg 0 21:24:50 T:139800589981760 NOTICE: OnWake: Running resume jobs 21:24:50 T:139800589981760 NOTICE: OnWake: Restarting lirc 21:24:50 T:139800011060992 DEBUG: Thread RemoteControl start, auto delete: false 21:24:50 T:139800011060992 INFO: LIRC Process: using: /run/lirc/lircd 21:24:50 T:139800011060992 INFO: LIRC Connect: successfully started 21:24:50 T:139800011060992 DEBUG: Thread RemoteControl 139800011060992 terminating 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/pcm/pcm_dmix.c:1029:(snd_pcm_dmix_open) unable to open slave 21:24:50 T:139800500791040 INFO: CAESinkALSA - Unable to open device "default" for playback 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:768:(parse_card) cannot find card '$CARD' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_card_driver returned error: No such device 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:392:(snd_func_concat) error evaluating strings 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_concat returned error: No such device 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1251:(snd_func_refer) error evaluating name 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such device 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such device 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter DEV 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter DEV 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.front.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround40.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround71.1:CARD=HDMI' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.iec958.1:CARD=HDMI,AES0=4,AES1=130,AES2=0,AES3=2' 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:24:50 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:24:50 T:139800500791040 DEBUG: CAESinkALSA - HDMI device "hdmi:CARD=HDMI,DEV=0" may be unconnected (no ELD data) 21:24:50 T:139800500791040 DEBUG: PulseAudio: Context authorizing 21:24:50 T:139800500791040 DEBUG: PulseAudio: Context setting name 21:24:50 T:139800500791040 DEBUG: PulseAudio: Context ready 21:24:50 T:139800500791040 NOTICE: Found 2 Lists of Devices 21:24:50 T:139800500791040 NOTICE: Enumerated ALSA devices: 21:24:50 T:139800500791040 NOTICE: Device 1 21:24:50 T:139800500791040 NOTICE: m_deviceName : hdmi:CARD=HDMI,DEV=0 21:24:50 T:139800500791040 NOTICE: m_displayName : HDA ATI 21:24:50 T:139800500791040 NOTICE: m_displayNameExtra: HDMI 21:24:50 T:139800500791040 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 21:24:50 T:139800500791040 NOTICE: m_channels : FL,FR,LFE,FC,BC,BL,BR,FLOC,FROC,BLOC,BROC,TFC,TC,TFL,TFR,UNKNOWN1 21:24:50 T:139800500791040 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 21:24:50 T:139800500791040 NOTICE: m_dataFormats : AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_LPCM,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_EAC3,AE_FMT_DTS,AE_FMT_AC3,AE_FMT_AAC 21:24:50 T:139800500791040 NOTICE: Enumerated PULSE devices: 21:24:50 T:139800500791040 NOTICE: Device 1 21:24:50 T:139800500791040 NOTICE: m_deviceName : Default 21:24:50 T:139800500791040 NOTICE: m_displayName : Default 21:24:50 T:139800500791040 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 21:24:50 T:139800500791040 NOTICE: m_deviceType : AE_DEVTYPE_PCM 21:24:50 T:139800500791040 NOTICE: m_channels : FL,FR 21:24:50 T:139800500791040 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 21:24:50 T:139800500791040 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 21:24:50 T:139800492398336 INFO: CActiveAESink::OpenSink - initialize sink 21:24:50 T:139800492398336 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 21:24:50 T:139800492398336 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 21:24:50 T:139800492398336 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 21:24:50 T:139800492398336 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR" 21:24:50 T:139800492398336 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 21:24:50 T:139800492398336 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 21:24:50 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 21:24:51 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 21:24:51 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 21:24:51 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 21:24:51 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 21:24:51 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 21:24:51 T:139800492398336 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 21:24:51 T:139800492398336 DEBUG: Output Device : HDA ATI 21:24:51 T:139800492398336 DEBUG: Sample Rate : 44100 21:24:51 T:139800492398336 DEBUG: Sample Format : AE_FMT_S32NE 21:24:51 T:139800492398336 DEBUG: Channel Count : 2 21:24:51 T:139800492398336 DEBUG: Channel Layout: FL,FR 21:24:51 T:139800492398336 DEBUG: Frames : 464 21:24:51 T:139800492398336 DEBUG: Frame Samples : 928 21:24:51 T:139800492398336 DEBUG: Frame Size : 8 21:24:51 T:139800492398336 DEBUG: ffmpeg[7F25D6B05700]: [SWR] Using fltp internally between filters 21:24:51 T:139800500791040 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 21:24:51 T:139800589981760 DEBUG: CAnnouncementManager - Announcement: OnWake from xbmc 21:24:51 T:139800589981760 DEBUG: GOT ANNOUNCEMENT, type: 8, from xbmc, message OnWake 21:24:51 T:139800589981760 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=System, sender=xbmc, message=OnWake) 21:24:51 T:139800589981760 DEBUG: Announce - reconnecting to the CEC adapter after standby mode 21:24:51 T:139799007069952 INFO: PVRChannelGroupsContainer - SearchMissingChannelIcons - starting channel icon search 21:24:51 T:139799591622400 DEBUG: CPVRChannelGroupsContainer - Update - updating channel groups 21:24:51 T:139799591622400 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'TV channels' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'HDTV' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'SDTV' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ARD' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'ZDFvision' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: PVR - GetChannelGroupMembers - get group members for group 'Digital Free' from add-on 'Tvheadend:192.168.1.101:9982 [connected]' 21:24:51 T:139799591622400 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups 21:24:51 T:139799591622400 DEBUG: CPVRChannelGroupsContainer - Update - updating channels 21:24:51 T:139799591622400 DEBUG: CPVRChannelGroups - PersistAll - persisting all changes in channel groups 21:24:51 T:139799591622400 DEBUG: Previous line repeats 1 times. 21:24:51 T:139799591622400 DEBUG: CPVRRecordings - Update - updating recordings 21:24:51 T:139799591622400 DEBUG: CPVRTimers - Update - updating timers 21:24:51 T:139799591622400 DEBUG: PVRTimers - UpdateEntries - updated timer 8 on client 22828 21:24:51 T:139799591622400 DEBUG: PVRTimers - UpdateEntries - updated timer 7 on client 22828 21:24:51 T:139799591622400 DEBUG: PVRTimers - UpdateEntries - updated timer 1995095200 on client 22828 21:24:51 T:139800002668288 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 21:24:51 T:139800589981760 DEBUG: Previous line repeats 1 times. 21:24:51 T:139800589981760 DEBUG: InitialiseFeature - using libCEC v3.0.1 21:24:51 T:139800589981760 DEBUG: LogindUPowerSyscall - inhibit lock taken, fd 54 21:24:51 T:139800002668288 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 21:24:51 T:139800011060992 DEBUG: Thread CECAdapter start, auto delete: false 21:24:51 T:139800011060992 DEBUG: OpenConnection - opening a connection to the CEC adapter: /dev/ttyACM0 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Broadcast (F): osd name set to 'Broadcast' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - connection opened, clearing any previous input and waiting for active transmissions to end before starting 21:24:51 T:139800589981760 DEBUG: ------ Window Init (DialogKaiToast.xml) ------ 21:24:51 T:139800151701248 DEBUG: CecLogMessage - communication thread started 21:24:51 T:139800589981760 DEBUG: ------ Window Deinit (DialogBusy.xml) ------ 21:24:51 T:139800151701248 DEBUG: CecLogMessage - turning controlled mode on 21:24:51 T:139799641978624 DEBUG: RunQuery took 15 ms for 127 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - connection opened 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << Broadcast (F) -> TV (0): POLL 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << f0 21:24:51 T:139800151701248 DEBUG: CecLogMessage - setting the line timeout to 3 21:24:51 T:139800151701248 DEBUG: CecLogMessage - processor thread started 21:24:51 T:139800151701248 DEBUG: CecLogMessage - >> POLL sent 21:24:51 T:139800151701248 DEBUG: CecLogMessage - TV (0): device status changed into 'present' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << requesting vendor ID of 'TV' (0) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << f0:8c 21:24:51 T:139799641978624 DEBUG: RunQuery took 1 ms for 8 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - >> 0f:87:00:80:45 21:24:51 T:139800151701248 DEBUG: CecLogMessage - TV (0): vendor = Panasonic (008045) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): device vendor id (87) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - expected response received (87: device vendor id) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - replacing the command handler for device 'TV' (0) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - registering new CEC client - v3.0.0 21:24:51 T:139800151701248 DEBUG: CecLogMessage - detecting logical address for type 'playback device' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - trying logical address 'Playback 1' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << Playback 1 (4) -> Playback 1 (4): POLL 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << 44 21:24:51 T:139800002668288 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - >> POLL sent 21:24:51 T:139800151701248 DEBUG: CecLogMessage - logical address 'Playback 1' already taken 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 1 (4): device status changed into 'present' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - trying logical address 'Playback 2' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Playback 2 (8): POLL 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << 88 21:24:51 T:139799641978624 DEBUG: GetSongsFullByWhere query = SELECT songview.*, song_artist.idArtist AS idArtist, artist.strArtist AS strArtist, artist.strMusicBrainzArtistID AS strMusicBrainzArtistID FROM songview LEFT JOIN song_artist on song_artist.idsong = songview.idsong LEFT JOIN artist ON song_artist.idArtist = artist.idArtist WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) 21:24:51 T:139799641978624 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview LEFT JOIN albumartistview on albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 21:24:51 T:139799641978624 DEBUG: GetAlbumsByWhere - query took 1 ms 21:24:51 T:139799641978624 DEBUG: RunQuery took 2 ms for 0 items query: select * from musicvideo_view WHERE ((musicvideo_view.playCount IS NULL OR musicvideo_view.playCount < 1)) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << 88 21:24:51 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:51 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:51 T:139800151701248 DEBUG: CecLogMessage - using logical address 'Playback 2' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): device status changed into 'handled by libCEC' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): power status changed from 'unknown' to 'on' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): vendor = Pulse Eight (001582) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): CEC version 1.4 21:24:51 T:139800151701248 DEBUG: CecLogMessage - AllocateLogicalAddresses - device '0', type 'playback device', LA '8' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - setting ackmask to 100 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): osd name set to 'Kodi' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): menu language set to 'eng' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - trying to get the physical address via ADL 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - ADL returned physical address 0000 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - trying to get the physical address via nvidia driver 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - nvidia driver returned physical address 0000 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - trying to get the physical address via drm files 21:24:51 T:139800151701248 DEBUG: CecLogMessage - GetPhysicalAddress - drm files returned physical address 1200 21:24:51 T:139800151701248 DEBUG: CecLogMessage - AutodetectPhysicalAddress - autodetected physical address '1200' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): physical address changed from ffff to 1200 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): physical adddress 1200 21:24:51 T:139800151701248 DEBUG: CecLogMessage - << 8f:84:12:00:04 21:24:51 T:139800002668288 DEBUG: GetMappingForDevice - device (2548:1002) mapped to Pulse-Eight CEC Adapter (type = cec) 21:24:51 T:139800151701248 DEBUG: CecLogMessage - using persisted autonomous mode setting: 'enabled' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - using persisted CEC version setting: '1.4' 21:24:51 T:139800151701248 DEBUG: CecLogMessage - using persisted logical address setting: 'Playback 2' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - using persisted device type setting: 'playback device' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - using persisted logical address mask setting: '910' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - using persisted device name setting: 'Kodi' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - using persisted physical address setting: '1200' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - making Playback 2 (8) the active source 21:24:52 T:139800151701248 DEBUG: CecLogMessage - TV (0): power status changed from 'unknown' to 'in transition from standby to on' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - marking the adapter as active source 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> source activated: Playback 2 (8) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - scheduling active source message for 'Playback 2' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - CEC client registered: libCEC version = 3.0.1, client version = 3.0.0, firmware version = 4, firmware build date: Thu Dec 6 11:15:20 2012 +0000, logical address(es) = Playback 2 (8) , physical address: 1.2.0.0, git revision: f51b881 , compiled on Tue May 17 09:25:36 UTC 2016 by chewitt@buildbox on Linux 4.4.0-17-generic (x86_64), features: P8_USB, P8_detect, randr 21:24:52 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8): vendor = Panasonic (008045) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - replacing the command handler for device 'Playback 2' (8) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): OSD name 'Kodi' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 80:47:4b:6f:64:69 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << requesting power status of 'TV' (0) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 80:8f 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> 08:00:47:00 21:24:52 T:139800151701248 DEBUG: CecLogMessage - marking opcode 'set osd name' as unsupported feature for device 'TV' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): feature abort ( 0) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> 08:90:00 21:24:52 T:139800151701248 DEBUG: CecLogMessage - TV (0): power status changed from 'in transition from standby to on' to 'on' 21:24:52 T:139800011060992 DEBUG: OpenConnection - connection to the CEC adapter opened 21:24:52 T:139800011060992 DEBUG: SetConfigurationFromLibCEC - settings updated by libCEC 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 80:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 21:24:52 T:139800151701248 DEBUG: CecLogMessage - expected response received (90: report power status) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): report power status (90) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << powering on 'TV' (0) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 80:04 21:24:52 T:139799600015104 DEBUG: Thread CECAdapterUpdate start, auto delete: false 21:24:52 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8) was already marked as active source 21:24:52 T:139800151701248 DEBUG: CecLogMessage - >> source activated: Playback 2 (8) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - sending active source message for 'Playback 2' 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << powering on 'TV' (0) 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 80:04 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:52 T:139800151701248 DEBUG: Previous line repeats 1 times. 21:24:52 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): menu state 'activated' 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): menu state 'activated' 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 80:8e:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> 08:8c 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): vendor id Panasonic (8045) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 8f:87:00:80:45 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> 08:8f 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): on 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 80:90:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 80:8e:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): give device vendor id (8C) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): give device power status (8F) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << powering on 'TV' (0) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 80:04 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Audio (5): POLL 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 85 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> POLL sent 21:24:53 T:139800151701248 DEBUG: CecLogMessage - Audio (5): device status changed into 'present' 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << requesting vendor ID of 'Audio' (5) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 85:8c 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> 5f:87:00:80:45 21:24:53 T:139800151701248 DEBUG: CecLogMessage - Audio (5): vendor = Panasonic (008045) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - expected response received (87: device vendor id) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> Audio (5) -> Broadcast (F): device vendor id (87) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - replacing the command handler for device 'Audio' (5) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << requesting power status of 'Audio' (5) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 85:8f 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> 58:90:00 21:24:53 T:139800151701248 DEBUG: CecLogMessage - Audio (5): power status changed from 'unknown' to 'on' 21:24:53 T:139800151701248 DEBUG: CecLogMessage - expected response received (90: report power status) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - >> Audio (5) -> Playback 2 (8): report power status (90) 21:24:53 T:139800151701248 DEBUG: CecLogMessage - 'Audio' (5) is already 'on' 21:24:53 T:139799600015104 DEBUG: UpdateMenuLanguage - using TV menu language is disabled 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Recorder 1 (1): POLL 21:24:53 T:139800151701248 DEBUG: CecLogMessage - << 81 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 81 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Recorder 1 (1): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Recorder 2 (2): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 82 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 82 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Recorder 2 (2): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Tuner 1 (3): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 83 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 83 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Tuner 1 (3): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Tuner 2 (6): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 86 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 86 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Tuner 2 (6): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Tuner 3 (7): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 87 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 87 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Tuner 3 (7): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Recorder 3 (9): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 89 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 89 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Recorder 3 (9): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Tuner 4 (A): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8a 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8a 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Tuner 4 (A): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Playback 3 (B): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8b 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8b 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Playback 3 (B): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Reserved 1 (C): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8c 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8c 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Reserved 1 (C): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Reserved 2 (D): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8d 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8d 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Reserved 2 (D): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> Free use (E): POLL 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8e 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 8e 21:24:54 T:139800151701248 DEBUG: CecLogMessage - CEC transmission - received response - TRANSMIT_FAILED_ACK 21:24:54 T:139800151701248 DEBUG: CecLogMessage - >> POLL not sent 21:24:54 T:139800151701248 DEBUG: CecLogMessage - Free use (E): device status changed into 'not present' 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << requesting OSD name of 'Audio' (5) 21:24:54 T:139800151701248 DEBUG: CecLogMessage - << 85:46 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> 58:47:41:56:52:2d:31:39:30:39 21:24:55 T:139800151701248 DEBUG: CecLogMessage - Audio (5): osd name set to 'AVR-1909' 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> Audio (5) -> Playback 2 (8): set osd name (47) 21:24:55 T:139799600015104 DEBUG: UpdateAudioSystemStatus - CEC capable amplifier found (AVR-1909). volume will be controlled on the amp 21:24:55 T:139799600015104 DEBUG: CAnnouncementManager - Announcement: OnVolumeChanged from xbmc 21:24:55 T:139799600015104 DEBUG: GOT ANNOUNCEMENT, type: 64, from xbmc, message OnVolumeChanged 21:24:55 T:139799600015104 DEBUG: AddOnLog: Tvheadend HTSP Client: pvr.hts - Announce(flag=Application, sender=xbmc, message=OnVolumeChanged) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - expected response received (47: set osd name) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> 08:00:89:03 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): feature abort ( 0) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> 08:89:10:01:05 21:24:55 T:139800151701248 DEBUG: CecLogMessage - << 80:89:10:02:ff:ff:00:05:05:45:55:5c:58:32 21:24:55 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8) was already marked as active source 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): vendor command (89) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> source activated: Playback 2 (8) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - scheduling active source message for 'Playback 2' 21:24:55 T:139799994275584 DEBUG: RunQuery took 7 ms for 127 items query: select * from movie_view WHERE ((movie_view.playCount IS NULL OR movie_view.playCount < 1)) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> 0f:86:12:00 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) sets stream path to physical address 1200 21:24:55 T:139800151701248 DEBUG: CecLogMessage - Playback 2 (8) was already marked as active source 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> source activated: Playback 2 (8) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:55 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:55 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Broadcast (F): set stream path (86) 21:24:55 T:139799994275584 DEBUG: RunQuery took 2 ms for 8 items query: select * from episode_view WHERE ((episode_view.playCount IS NULL OR episode_view.playCount < 1)) 21:24:56 T:139799994275584 DEBUG: RunQuery took 1 ms for 1 items query: select * from musicvideo_view 21:24:56 T:139799994275584 DEBUG: GetAlbumsByWhere query: SELECT albumview.*, albumartistview.* FROM albumview LEFT JOIN albumartistview on albumartistview.idalbum = albumview.idalbum WHERE albumview.strReleaseType = 'album' 21:24:56 T:139799994275584 DEBUG: GetAlbumsByWhere - query took 1 ms 21:24:56 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:09 21:24:56 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton root menu (9) D:0ms cur:510f190 21:24:56 T:139800151701248 DEBUG: CecLogMessage - key pressed: root menu (9) current(ff) duration(0) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - Changed key root menu (9) D:0ms cur:ff 21:24:56 T:139800151701248 DEBUG: CecLogMessage - key pressed: root menu (9, 0) 21:24:56 T:139800151701248 DEBUG: PushCecKeypress - received key ed duration 0 21:24:56 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17124.599 21:24:56 T:139800151701248 DEBUG: CecLogMessage - Key root menu: idle (duration:0) (9) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:56 T:139799994275584 DEBUG: GetArtistsByWhere query: SELECT artistview.* FROM artistview WHERE (artistview.idArtist IN (SELECT song_artist.idArtist FROM song_artist) OR artistview.idArtist IN (SELECT album_artist.idArtist FROM album_artist)) and artistview.strArtist != '' and artistview.strArtist <> 'Verschiedene Interpreten' 21:24:56 T:139799994275584 DEBUG: Time to retrieve artists from dataset = 1 21:24:56 T:139799994275584 DEBUG: GetSongsFullByWhere query = SELECT songview.*, song_artist.idArtist AS idArtist, artist.strArtist AS strArtist, artist.strMusicBrainzArtistID AS strMusicBrainzArtistID FROM songview LEFT JOIN song_artist on song_artist.idsong = songview.idsong LEFT JOIN artist ON song_artist.idArtist = artist.idArtist WHERE ((CAST(songview.iTimesPlayed as DECIMAL(5,1)) < 1)) 21:24:56 T:139800589981760 DEBUG: OnKey: 237 (0xed) pressed, action is PreviousMenu 21:24:56 T:139800589981760 DEBUG: CGUIWindowManager::PreviousWindow: Deactivate 21:24:56 T:139800151701248 DEBUG: CecLogMessage - >> 08:a0:00:80:45:06:05 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:56 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): vendor command with id (A0) 21:24:56 T:139799994275584 DEBUG: Skin Widgets: Total time needed to request random queries: 0:00:00.583181 21:24:56 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17124.895 21:24:56 T:139800151701248 DEBUG: CecLogMessage - Key root menu: idle (duration:0) (9) timeout:204ms (rel:204,rep:0,prs:1,rel:0) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17125.100 21:24:56 T:139800151701248 DEBUG: CecLogMessage - Key unknown: idle (duration:501) (ff) timeout:1000ms (rel:0,rep:0,prs:0,rel:0) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << powering on 'TV' (0) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << 80:04 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> TV (0): menu state 'activated' 21:24:56 T:139800151701248 DEBUG: CecLogMessage - << 80:8e:00 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:03 21:24:57 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton left (3) D:0ms cur:510f190 21:24:57 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3) current(ff) duration(0) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - Changed key left (3) D:0ms cur:ff 21:24:57 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3, 0) 21:24:57 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 0 21:24:57 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17125.632 21:24:57 T:139800151701248 DEBUG: CecLogMessage - Key left: idle (duration:0) (3) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:57 T:139800589981760 DEBUG: OnKey: 169 (0xa9) pressed, action is Left 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:57 T:139800151701248 DEBUG: CecLogMessage - key released: left (3) D:212ms 21:24:57 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 212 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> 08:a0:00:80:45:06:05 21:24:57 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): vendor command with id (A0) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:03 21:24:57 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton left (3) D:0ms cur:510f190 21:24:57 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3) current(ff) duration(0) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - Changed key left (3) D:0ms cur:ff 21:24:57 T:139800151701248 DEBUG: CecLogMessage - key pressed: left (3, 0) 21:24:57 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 0 21:24:57 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17126.371 21:24:57 T:139800151701248 DEBUG: CecLogMessage - Key left: idle (duration:0) (3) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:57 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:57 T:139800589981760 DEBUG: OnKey: 169 (0xa9) pressed, action is Left 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:58 T:139800151701248 DEBUG: CecLogMessage - key released: left (3) D:211ms 21:24:58 T:139800151701248 DEBUG: PushCecKeypress - received key a9 duration 211 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> 08:a0:00:80:45:06:05 21:24:58 T:139800151701248 DEBUG: CecLogMessage - << Playback 2 (8) -> broadcast (F): active source (1200) 21:24:58 T:139800151701248 DEBUG: CecLogMessage - << 8f:82:12:00 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): vendor command with id (A0) 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:04 21:24:58 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:510f190 21:24:58 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 21:24:58 T:139800151701248 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 21:24:58 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4, 0) 21:24:58 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:24:58 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17127.518 21:24:58 T:139800151701248 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:58 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:59 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:59 T:139800151701248 DEBUG: CecLogMessage - key released: right (4) D:74ms 21:24:59 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 74 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:04 21:24:59 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:510f190 21:24:59 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 21:24:59 T:139800151701248 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 21:24:59 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4, 0) 21:24:59 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:24:59 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17128.026 21:24:59 T:139800151701248 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:24:59 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:24:59 T:139800151701248 DEBUG: CecLogMessage - key released: right (4) D:202ms 21:24:59 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 202 21:24:59 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:00 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:25:00 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:510f190 21:25:00 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:25:00 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:25:00 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:25:00 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:25:00 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17128.773 21:25:00 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:00 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:00 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:25:00 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:00 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:75ms 21:25:00 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:25:00 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:00 T:139800589981760 DEBUG: ------ Window Deinit (DialogKaiToast.xml) ------ 21:25:01 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:25:01 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:25:03 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:00 21:25:03 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton select (0) D:0ms cur:510f190 21:25:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0) current(ff) duration(0) 21:25:03 T:139800151701248 DEBUG: CecLogMessage - Changed key select (0) D:0ms cur:ff 21:25:03 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0, 0) 21:25:03 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:25:03 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17132.495 21:25:03 T:139800151701248 DEBUG: CecLogMessage - Key select: idle (duration:0) (0) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:03 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:03 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:25:03 T:139800589981760 DEBUG: Activating window ID: 10004 21:25:04 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:04 T:139800151701248 DEBUG: CecLogMessage - key released: select (0) D:78ms 21:25:04 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 78 21:25:04 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:04 T:139800589981760 DEBUG: ------ Window Deinit (Home.xml) ------ 21:25:04 T:139800589981760 DEBUG: ------ Window Init (Settings.xml) ------ 21:25:06 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:00 21:25:06 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton select (0) D:0ms cur:510f190 21:25:06 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0) current(ff) duration(0) 21:25:06 T:139800151701248 DEBUG: CecLogMessage - Changed key select (0) D:0ms cur:ff 21:25:06 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0, 0) 21:25:06 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:25:06 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17134.623 21:25:06 T:139800151701248 DEBUG: CecLogMessage - Key select: idle (duration:0) (0) timeout:499ms (rel:499,rep:0,prs:1,rel:0) 21:25:06 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:06 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:25:06 T:139800589981760 DEBUG: Activating window ID: 10016 21:25:06 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:06 T:139800151701248 DEBUG: CecLogMessage - key released: select (0) D:75ms 21:25:06 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 75 21:25:06 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:06 T:139800589981760 DEBUG: ------ Window Deinit (Settings.xml) ------ 21:25:06 T:139800589981760 DEBUG: ------ Window Init (SettingsCategory.xml) ------ 21:25:06 T:139800511342336 DEBUG: CALSAHControlMonitor - Monitored ALSA hctl value changed 21:25:06 T:139800500791040 DEBUG: CActiveAE - device change event 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/pcm/pcm_dmix.c:1029:(snd_pcm_dmix_open) unable to open slave 21:25:06 T:139800500791040 INFO: CAESinkALSA - Unable to open device "default" for playback 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:768:(parse_card) cannot find card '$CARD' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_card_driver returned error: No such device 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:392:(snd_func_concat) error evaluating strings 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_concat returned error: No such device 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1251:(snd_func_refer) error evaluating name 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such device 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such device 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter DEV 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter DEV 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.front.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround40.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround51.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.surround71.1:CARD=HDMI' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/confmisc.c:1286:(snd_func_refer) Unable to find definition 'cards.HDA-Intel.pcm.iec958.1:CARD=HDMI,AES0=4,AES1=130,AES2=0,AES3=2' 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4292:(_snd_config_evaluate) function snd_func_refer returned error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4771:(snd_config_expand) Evaluate error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4622:(parse_args) Unknown parameter CARD 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4755:(snd_config_expand) Parse arguments error: No such file or directory 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI,DEV=0 21:25:06 T:139800500791040 INFO: CAESinkALSA - ALSA: /home/chewitt/LibreELEC.70-official/build.LibreELEC-Generic.x86_64-7.0.1/alsa-lib-1.1.1/src/conf.c:4738:(snd_config_expand) Unknown parameters CARD=HDMI 21:25:06 T:139800500791040 DEBUG: PulseAudio: Context authorizing 21:25:06 T:139800500791040 DEBUG: PulseAudio: Context setting name 21:25:06 T:139800500791040 DEBUG: PulseAudio: Context ready 21:25:06 T:139800500791040 NOTICE: Found 2 Lists of Devices 21:25:06 T:139800500791040 NOTICE: Enumerated ALSA devices: 21:25:06 T:139800500791040 NOTICE: Device 1 21:25:06 T:139800500791040 NOTICE: m_deviceName : hdmi:CARD=HDMI,DEV=0 21:25:06 T:139800500791040 NOTICE: m_displayName : HDA ATI 21:25:06 T:139800500791040 NOTICE: m_displayNameExtra: @@@ 21:25:06 T:139800500791040 NOTICE: m_deviceType : AE_DEVTYPE_HDMI 21:25:06 T:139800500791040 NOTICE: m_channels : FL,FR,LFE,FC,BL,BR,BC,BLOC,BROC 21:25:06 T:139800500791040 NOTICE: m_sampleRates : 32000,44100,48000,88200,96000,176400,192000 21:25:06 T:139800500791040 NOTICE: m_dataFormats : AE_FMT_LPCM,AE_FMT_AC3,AE_FMT_DTS,AE_FMT_EAC3,AE_FMT_DTSHD,AE_FMT_TRUEHD,AE_FMT_S32NE,AE_FMT_S16NE,AE_FMT_S16LE,AE_FMT_AAC 21:25:06 T:139800500791040 NOTICE: Enumerated PULSE devices: 21:25:06 T:139800500791040 NOTICE: Device 1 21:25:06 T:139800500791040 NOTICE: m_deviceName : Default 21:25:06 T:139800500791040 NOTICE: m_displayName : Default 21:25:06 T:139800500791040 NOTICE: m_displayNameExtra: Bluetooth Audio (PULSEAUDIO) 21:25:06 T:139800500791040 NOTICE: m_deviceType : AE_DEVTYPE_PCM 21:25:06 T:139800500791040 NOTICE: m_channels : FL,FR 21:25:06 T:139800500791040 NOTICE: m_sampleRates : 5512,8000,11025,16000,22050,32000,44100,48000,64000,88200,96000,176400,192000,384000 21:25:06 T:139800500791040 NOTICE: m_dataFormats : AE_FMT_U8,AE_FMT_S16NE,AE_FMT_S24NE3,AE_FMT_S24NE4,AE_FMT_S32NE,AE_FMT_FLOAT 21:25:06 T:139800492398336 INFO: CActiveAESink::OpenSink - initialize sink 21:25:06 T:139800492398336 DEBUG: CActiveAESink::OpenSink - trying to open device ALSA:hdmi:CARD=HDMI,DEV=0 21:25:06 T:139800492398336 INFO: CAESinkALSA::Initialize - Attempting to open device "hdmi:CARD=HDMI,DEV=0" 21:25:06 T:139800492398336 INFO: CAESinkALSA::Initialize - Opened device "hdmi:CARD=HDMI,DEV=0,AES0=0x04,AES1=0x82,AES2=0x00,AES3=0x00" 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::SelectALSAChannelMap - Selected ALSA map "FL FR" 21:25:06 T:139800492398336 INFO: CAESinkALSA::InitializeHW - Your hardware does not support AE_FMT_FLOAT, trying other formats 21:25:06 T:139800492398336 INFO: CAESinkALSA::InitializeHW - Using data format AE_FMT_S32NE 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Request: periodSize 2205, bufferSize 8820 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Got: periodSize 464, bufferSize 8816 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::InitializeHW - Setting timeout to 200 ms 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Input Channel Count: 2 Output Channel Count: 2 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Requested Layout: FL,FR 21:25:06 T:139800492398336 DEBUG: CAESinkALSA::GetChannelLayout - Got Layout: FL,FR (ALSA: FL FR) 21:25:06 T:139800492398336 DEBUG: CActiveAESink::OpenSink - ALSA Initialized: 21:25:06 T:139800492398336 DEBUG: Output Device : HDA ATI 21:25:06 T:139800492398336 DEBUG: Sample Rate : 44100 21:25:06 T:139800492398336 DEBUG: Sample Format : AE_FMT_S32NE 21:25:06 T:139800492398336 DEBUG: Channel Count : 2 21:25:06 T:139800492398336 DEBUG: Channel Layout: FL,FR 21:25:06 T:139800492398336 DEBUG: Frames : 464 21:25:06 T:139800492398336 DEBUG: Frame Samples : 928 21:25:06 T:139800492398336 DEBUG: Frame Size : 8 21:25:06 T:139800492398336 DEBUG: ffmpeg[7F25D6B05700]: [SWR] Using fltp internally between filters 21:25:06 T:139800500791040 DEBUG: CActiveAE::ClearDiscardedBuffers - buffer pool deleted 21:25:08 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:25:08 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:510f190 21:25:08 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:25:08 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:25:08 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:25:08 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:25:08 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17136.550 21:25:08 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:08 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:08 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:25:08 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:08 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:208ms 21:25:08 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 208 21:25:08 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:09 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:25:09 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:25:13 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:510f190 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:25:13 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:25:13 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:25:13 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17141.683 21:25:13 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:13 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:69ms 21:25:13 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 69 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:25:13 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:510f190 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:25:13 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:25:13 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:25:13 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17142.032 21:25:13 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:13 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:13 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:74ms 21:25:13 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 74 21:25:13 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:14 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:01 21:25:14 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton up (1) D:0ms cur:510f190 21:25:14 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1) current(ff) duration(0) 21:25:14 T:139800151701248 DEBUG: CecLogMessage - Changed key up (1) D:0ms cur:ff 21:25:14 T:139800151701248 DEBUG: CecLogMessage - key pressed: up (1, 0) 21:25:14 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 0 21:25:14 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17143.341 21:25:14 T:139800151701248 DEBUG: CecLogMessage - Key up: idle (duration:0) (1) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:14 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:14 T:139800589981760 DEBUG: OnKey: 166 (0xa6) pressed, action is Up 21:25:14 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:14 T:139800151701248 DEBUG: CecLogMessage - key released: up (1) D:75ms 21:25:14 T:139800151701248 DEBUG: PushCecKeypress - received key a6 duration 75 21:25:14 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:15 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:04 21:25:15 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton right (4) D:0ms cur:510f190 21:25:15 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4) current(ff) duration(0) 21:25:15 T:139800151701248 DEBUG: CecLogMessage - Changed key right (4) D:0ms cur:ff 21:25:15 T:139800151701248 DEBUG: CecLogMessage - key pressed: right (4, 0) 21:25:15 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 0 21:25:15 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17143.878 21:25:15 T:139800151701248 DEBUG: CecLogMessage - Key right: idle (duration:0) (4) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:15 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:15 T:139800589981760 DEBUG: OnKey: 168 (0xa8) pressed, action is Right 21:25:15 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:15 T:139800151701248 DEBUG: CecLogMessage - key released: right (4) D:73ms 21:25:15 T:139800151701248 DEBUG: PushCecKeypress - received key a8 duration 73 21:25:15 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:25:16 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:510f190 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:25:16 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:25:16 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17144.611 21:25:16 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:16 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:75ms 21:25:16 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:25:16 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:510f190 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:25:16 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:25:16 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17145.010 21:25:16 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:16 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:16 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:75ms 21:25:16 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 75 21:25:16 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:02 21:25:17 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton down (2) D:0ms cur:510f190 21:25:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2) current(ff) duration(0) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - Changed key down (2) D:0ms cur:ff 21:25:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: down (2, 0) 21:25:17 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 0 21:25:17 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17145.579 21:25:17 T:139800151701248 DEBUG: CecLogMessage - Key down: idle (duration:0) (2) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:17 T:139800589981760 DEBUG: OnKey: 167 (0xa7) pressed, action is Down 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> 08:45 21:25:17 T:139800151701248 DEBUG: CecLogMessage - key released: down (2) D:74ms 21:25:17 T:139800151701248 DEBUG: PushCecKeypress - received key a7 duration 74 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control release (45) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> 40:8f 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> 04:90:00 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> 08:44:00 21:25:17 T:139800151701248 DEBUG: CecLogMessage - SetCurrentButton select (0) D:0ms cur:510f190 21:25:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0) current(ff) duration(0) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - Changed key select (0) D:0ms cur:ff 21:25:17 T:139800151701248 DEBUG: CecLogMessage - key pressed: select (0, 0) 21:25:17 T:139800151701248 DEBUG: PushCecKeypress - received key b duration 0 21:25:17 T:139800151701248 DEBUG: CecLogMessage - CheckKeypressTimeout T:17146.129 21:25:17 T:139800151701248 DEBUG: CecLogMessage - Key select: idle (duration:0) (0) timeout:500ms (rel:500,rep:0,prs:1,rel:0) 21:25:17 T:139800151701248 DEBUG: CecLogMessage - >> TV (0) -> Playback 2 (8): user control pressed (44) 21:25:17 T:139800589981760 DEBUG: OnKey: 11 (0x0b) pressed, action is Select 21:25:17 T:139800589981760 NOTICE: Disabled debug logging due to GUI setting. Level 0. 21:25:17 T:139800589981760 NOTICE: Log level changed to "LOG_LEVEL_NORMAL"