search

plexinc / plex-media-player

Next generation Plex Desktop/Embedded Client
GNU General Public License v2.0
1.17k stars 170 forks source link

UI freezes on RPi, apparently while downloading updates #522

Open rcombs opened 7 years ago

rcombs commented 7 years ago

Not sure if this is a Web thing or a host thing, so filing here. Whenever the updater triggers, I see PMP's interface become completely unresponsive for several seconds until the update prompt appears. Here's a log snippet:

2017-02-16 06:18:10 [ DEBUG ] SystemComponent.cpp @ 153 - "System Information : build(rpi2)-arch(arm).dist(openelec).version(1.2.2.598-1c720b26).productid(4)" 
2017-02-16 06:18:10 [ DEBUG ] UpdaterComponent.cpp @ 86 - Checking for updates at: "https://plex.tv/updater/products/4/check.xml?version=1.2.2.598-1c720b26&build=rpi2-arm&distribution=openelec&channel=4&X-Plex-Token=xxxxxxxxxxxxxxxxxxxx" 
2017-02-16 06:18:11 [ DEBUG ] UpdaterComponent.cpp @ 358 - (QVariant(QVariantHash, QHash(("new", QVariant(QString, "Nothing"))("fixed", QVariant(QString, ""))("version", QVariant(QString, "1.2.2.599-0451a0df"))("full_package", QVariant(QVariantHash, QHash(("fileHash", QVariant(QString, "c349d15d011dc8fe1e2a9e2603bc1cfc98138a04"))("delta", QVariant(QString, "false"))("fileName", QVariant(QString, "PlexMediaPlayer-1.2.2.599-0451a0df.RPi2-arm.tar"))("manifestHash", QVariant(QString, ""))("manifest", QVariant(QString, "/updater/packages/27702/manifest"))("file", QVariant(QString, "/updater/packages/27702/file"))))))), QVariant(QVariantHash, QHash(("new", QVariant(QString, "Nothing"))("fixed", QVariant(QString, ""))("version", QVariant(QString, "1.2.2.601-549f7e41"))("full_package", QVariant(QVariantHash, QHash(("fileHash", QVariant(QString, "0a5c9d3b10e1d1814ab3cd8031a43ea0523b7e9d"))("delta", QVariant(QString, "false"))("fileName", QVariant(QString, "PlexMediaPlayer-1.2.2.601-549f7e41.RPi2-arm.tar"))("manifestHash", QVariant(QString, ""))("manifest", QVariant(QString, "/updater/packages/28409/manifest"))("file", QVariant(QString, "/updater/packages/28409/file"))))))), QVariant(QVariantHash, QHash(("new", QVariant(QString, "Nothing"))("fixed", QVariant(QString, ""))("version", QVariant(QString, "1.2.2.602-6254530a"))("full_package", QVariant(QVariantHash, QHash(("fileHash", QVariant(QString, "bd98dab672cc4a0eb5f044649205254e4e5be389"))("delta", QVariant(QString, "false"))("fileName", QVariant(QString, "PlexMediaPlayer-1.2.2.602-6254530a.RPi2-arm.tar"))("manifestHash", QVariant(QString, ""))("manifest", QVariant(QString, "/updater/packages/28455/manifest"))("file", QVariant(QString, "/updater/packages/28455/file"))))))), QVariant(QVariantHash, QHash(("new", QVariant(QString, "Nothing"))("fixed", QVariant(QString, ""))("version", QVariant(QString, "1.2.2.604-47c96c11"))("full_package", QVariant(QVariantHash, QHash(("fileHash", QVariant(QString, "cd9597d44fe3bf78da492e84e39ca8e4b28e6eb1"))("delta", QVariant(QString, "false"))("fileName", QVariant(QString, "PlexMediaPlayer-1.2.2.604-47c96c11.RPi2-arm.tar"))("manifestHash", QVariant(QString, ""))("manifest", QVariant(QString, "/updater/packages/29037/manifest"))("file", QVariant(QString, "/updater/packages/29037/file")))))))) 
2017-02-16 06:18:11 [ DEBUG ] UpdaterComponent.cpp @ 373 - QHash(("fileURL", QVariant(QString, "https://plex.tv/updater/packages/29037/file?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx"))("fileHash", QVariant(QString, "cd9597d44fe3bf78da492e84e39ca8e4b28e6eb1"))("delta", QVariant(QString, "false"))("fileName", QVariant(QString, "PlexMediaPlayer-1.2.2.604-47c96c11.RPi2-arm.tar"))("manifestHash", QVariant(QString, ""))("manifest", QVariant(QString, "/updater/packages/29037/manifest"))("new", QVariant(QString, "Nothing"))("fixed", QVariant(QString, ""))("file", QVariant(QString, "/updater/packages/29037/file"))("version", QVariant(QString, "1.2.2.604-47c96c11"))("manifestURL", QVariant(QString, "https://plex.tv/updater/packages/29037/manifest?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx"))) 
2017-02-16 06:18:11 [ INFO  ] UpdaterComponent.cpp @ 157 - Downloading update: "https://plex.tv/updater/packages/29037/file?X-Plex-Token=xxxxxxxxxxxxxxxxxxxx" to: "/storage/.cache/plexmediaplayer/updates/1.2.2.604-47c96c11/packages/PlexMediaPlayer-1.2.2.604-47c96c11.RPi2-arm.tar" 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] rcombs-NAS is unavailable at https://172-17-0-1.6b1f95cb84be4d9d870f529707dd6fe7.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Added connection http://172.17.0.1:32400 for rcombs-NAS 
2017-02-16 06:18:11 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Added insecure fallback connection for rcombs-NAS at http://172.17.0.1:32400 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Testing connection for rcombs-NAS at http://172.17.0.1:32400/ 
2017-02-16 06:18:11 [ INFO  ] JS: [CNP] CNP is not required to reach rcombs-NAS at http://172.17.0.1:32400 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Adonis is unavailable at https://10-0-1-8.3a0eb819075f4746a5dd0a22cf1018c1.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Added connection http://10.0.1.8:32400 for Adonis 
2017-02-16 06:18:11 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Added insecure fallback connection for Adonis at http://10.0.1.8:32400 
2017-02-16 06:18:11 [ INFO  ] JS: [Connections] Testing connection for Gregflix at https://10-0-1-30.668c60eeb3074acd9e8091825949693f.plex.direct:32400/ 
2017-02-16 06:18:11 [ INFO  ] JS: [CNP] CNP is not required to reach Gregflix at https://10-0-1-30.668c60eeb3074acd9e8091825949693f.plex.direct:32400 
2017-02-16 06:18:11 [ DEBUG ] UpdaterComponent.cpp @ 116 - Redirect: "https://nightlies.plex.tv/directdl/plexmediaplayer-openelec/dist-ninja/1.2.2.604-47c96c11/PlexMediaPlayer-1.2.2.604-47c96c11.RPi2-arm.tar" 
2017-02-16 06:18:11 [ DEBUG ] UpdaterComponent.cpp @ 129 - Redirecting to: "https://nightlies.plex.tv/directdl/plexmediaplayer-openelec/dist-ninja/1.2.2.604-47c96c11/PlexMediaPlayer-1.2.2.604-47c96c11.RPi2-arm.tar" 
2017-02-16 06:18:12 [ INFO  ] JS: [Connections] Office is unavailable at https://10-0-0-105.436fb910c8634392bc3dc7970e2f7c8e.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:12 [ INFO  ] JS: [Connections] Added connection http://10.0.0.105:32400 for Office 
2017-02-16 06:18:12 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:12 [ INFO  ] JS: [Connections] Added insecure fallback connection for Office at http://10.0.0.105:32400 
2017-02-16 06:18:12 [ INFO  ] JS: [Connections] Testing connection for Gregflix at https://172-17-0-1.668c60eeb3074acd9e8091825949693f.plex.direct:32400/ 
2017-02-16 06:18:12 [ INFO  ] JS: [CNP] CNP is not required to reach Gregflix at https://172-17-0-1.668c60eeb3074acd9e8091825949693f.plex.direct:32400 
2017-02-16 06:18:13 [ INFO  ] JS: [Connections] Office is unavailable at https://192-168-86-119.436fb910c8634392bc3dc7970e2f7c8e.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:13 [ INFO  ] JS: [Connections] Added connection http://192.168.86.119:32400 for Office 
2017-02-16 06:18:13 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:13 [ INFO  ] JS: [Connections] Added insecure fallback connection for Office at http://192.168.86.119:32400 
2017-02-16 06:18:13 [ INFO  ] JS: [Connections] Testing connection for debian-xeon at https://192-168-1-79.3862e998e2eb487cb212ec48d3f8ba19.plex.direct:32400/ 
2017-02-16 06:18:13 [ INFO  ] JS: [CNP] CNP is not required to reach debian-xeon at https://192-168-1-79.3862e998e2eb487cb212ec48d3f8ba19.plex.direct:32400 
2017-02-16 06:18:15 [ INFO  ] JS: [Connections] Norco is unavailable at https://10-0-1-16.ab7bf2c9eb644f0d8091a3ef9b02dc4c.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:15 [ INFO  ] JS: [Connections] Added connection http://10.0.1.16:32400 for Norco 
2017-02-16 06:18:15 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:15 [ INFO  ] JS: [Connections] Added insecure fallback connection for Norco at http://10.0.1.16:32400 
2017-02-16 06:18:15 [ INFO  ] JS: [Connections] Testing connection for PlexCorp at https://10-0-2-123.cde39f3b59634a33afd7e17077be2bef.plex.direct:32400/ 
2017-02-16 06:18:15 [ INFO  ] JS: [CNP] CNP is not required to reach PlexCorp at https://10-0-2-123.cde39f3b59634a33afd7e17077be2bef.plex.direct:32400 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:16 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_RIGHT" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton right (4) D:0ms cur:4 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4) current(ff) duration(0) 
2017-02-16 06:18:16 [ DEBUG ] CachedRegexMatcher.cpp @ 68 - No match for: "CEC" 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key right (4) D:0ms cur:ff 
2017-02-16 06:18:16 [ DEBUG ] CachedRegexMatcher.cpp @ 68 - No match for: "KEY_RIGHT" 
2017-02-16 06:18:16 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("right") 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4, 0) 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: device Audio (5) status changed to present after command user control pressed 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:44.532 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key right: idle (duration:0) (4) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: right (4) D:71ms 
2017-02-16 06:18:16 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:16 [ INFO  ] JS: [Connections] debian-xeon is unavailable at https://192-168-1-79.3862e998e2eb487cb212ec48d3f8ba19.plex.direct:32400/ (Status 0) 
2017-02-16 06:18:16 [ INFO  ] JS: [Connections] Added connection http://192.168.1.79:32400 for debian-xeon 
2017-02-16 06:18:16 [ INFO  ] JS: [Commands] Executing testServerConnection 
2017-02-16 06:18:16 [ INFO  ] JS: [Connections] Added insecure fallback connection for debian-xeon at http://192.168.1.79:32400 
2017-02-16 06:18:16 [ INFO  ] JS: [Connections] Testing connection for Servy McServerson at http://192.168.1.50:32400/ 
2017-02-16 06:18:16 [ INFO  ] JS: [CNP] CNP is not required to reach Servy McServerson at http://192.168.1.50:32400 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=44" 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=44" 
2017-02-16 06:18:17 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_PLAY" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton play (44) D:0ms cur:44 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: play (44) current(ff) duration(0) 
2017-02-16 06:18:17 [ DEBUG ] CachedRegexMatcher.cpp @ 68 - No match for: "KEY_PLAY" 
2017-02-16 06:18:17 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("play") 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key play (44) D:0ms cur:ff 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: play (44, 0) 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:46.113 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key play: idle (duration:0) (44) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:17 [ INFO  ] JS: [Commands] Executing playMedia 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=44" 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=44" 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: play (44) D:71ms 
2017-02-16 06:18:17 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:18 [ INFO  ] JS: [Commands] Executing persistPlayQueue 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton right (4) D:0ms cur:4 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4) current(ff) duration(0) 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key right (4) D:0ms cur:ff 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4, 0) 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:53.783 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key right: idle (duration:0) (4) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: right (4) D:71ms 
2017-02-16 06:18:25 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton right (4) D:0ms cur:4 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4) current(ff) duration(0) 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key right (4) D:0ms cur:ff 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: right (4, 0) 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:55.169 
2017-02-16 06:18:26 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key right: idle (duration:0) (4) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=4" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=4" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: right (4) D:71ms 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=3" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=3" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton left (3) D:0ms cur:3 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: left (3) current(ff) duration(0) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key left (3) D:0ms cur:ff 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: left (3, 0) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:56.049 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key left: idle (duration:0) (3) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=3" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=3" 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: left (3) D:70ms 
2017-02-16 06:18:27 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "44" , "1 parameter(s) :[0]=D" 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  true ) "1 parameter(s) :[0]=D" 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: SetCurrentButton exit (d) D:0ms cur:d 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: exit (d) current(ff) duration(0) 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Changed key exit (d) D:0ms cur:ff 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key pressed: exit (d, 0) 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: CheckKeypressTimeout T:56.734 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: Key exit: idle (duration:0) (d) timeout:1988294204ms (rel:500,rep:0,prs:500,rel:0) 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): user control pressed (44) 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 277 - CecCommand received  "8B" , "1 parameter(s) :[0]=D" 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 327 - CecCommand button (Down=  false ) "1 parameter(s) :[0]=D" 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: key released: exit (d) D:71ms 
2017-02-16 06:18:28 [ DEBUG ] InputCEC.cpp @ 239 - libCEC DEBUG: >> Audio (5) -> Playback 1 (4): vendor remote button up (8B) 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_RIGHT" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("right") 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_RIGHT" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("right") 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_LEFT" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:47 [ DEBUG ] CachedRegexMatcher.cpp @ 68 - No match for: "KEY_LEFT" 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("left") 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 152 - Input received: source: "CEC" keycode: "KEY_BACK" : InputBase::InputkeyState(KeyPressed) 
2017-02-16 06:18:47 [ DEBUG ] CachedRegexMatcher.cpp @ 68 - No match for: "KEY_BACK" 
2017-02-16 06:18:47 [ DEBUG ] InputComponent.cpp @ 223 - Emit input action: ("back")

Note the suspiciously long gap between 06:18:28 and 06:18:47.

ghost commented 7 years ago

Any idea what causes this? The other day we found that isMiniUpdateArchive invokes tar -tf on the update package and waits for it, but it looks like this only happens when the update is actually applied (t will restart or reboot once done).

tru commented 7 years ago

My guess is that it has to do with poor I/O - it's pretty evident if you have ever downloaded a big thing to the pi even with wget.

ghost commented 7 years ago

Well, I guess it kills all I/O to the SD card. But normally that shouldn't happen anyway.

LongChair commented 6 years ago

@rcombs : did you notice this again recently ? Could be indeed IO related, not sure how much we can do about this ?