xbianonpi / xbian

XBMC on Raspberry Pi, Bleeding Edge
https://xbian.org
GNU General Public License v3.0
294 stars 44 forks source link

[cubox] XBMC reboot loop #653

Closed CurlyMoo closed 9 years ago

CurlyMoo commented 9 years ago

Somehow XBMC is entering a reboot loop recently. Seems to be related to the init scripts.

Mon Dec 29 17:25:34 CET 2014 1251.05 8389221.09 starting xbmc-exits                      1.89 1.76 1.35 7/157 9473
Mon Dec 29 17:25:34 CET 2014 1251.04 8389221.09 started xbmc-changesettings              1.89 1.76 1.35 4/154 9473
Mon Dec 29 17:25:34 CET 2014 1251.07 8389221.09 stopped xbian-xbmc-cpufreq               1.89 1.76 1.35 3/149 9475
Mon Dec 29 17:25:34 CET 2014 1251.14 8389221.09 stopped tvonoff-xbmc                     1.89 1.76 1.35 2/147 9481
Mon Dec 29 17:25:35 CET 2014 1252.02 9731398.99 stopped xbmc-changesettings              1.89 1.76 1.35 2/148 9492
Mon Dec 29 17:25:35 CET 2014 1252.08 9731398.99 started xbmc-exits                       1.89 1.76 1.35 2/147 9502
Mon Dec 29 17:25:35 CET 2014 1252.15 9731398.99 starting xbmc                            1.89 1.76 1.35 6/148 9514
Mon Dec 29 17:25:35 CET 2014 1252.19 9731398.99 started xbian-xbmc-cpufreq               1.89 1.76 1.35 2/145 9520
Mon Dec 29 17:25:35 CET 2014 1252.25 9731398.99 stopped xbmc-exits                       1.89 1.76 1.35 5/148 9529
Mon Dec 29 17:25:36 CET 2014 1252.71 9731399.05 started wait-for-state  xbmc-loaded      1.89 1.76 1.35 6/152 9576
Mon Dec 29 17:27:16 CET 2014 1352.84 9843348.03 stopped wait-for-state  xbmc-loaded      0.77 1.51 1.31 4/168 9851
Mon Dec 29 17:27:16 CET 2014 1352.91 9843348.03 stopping xbmc                            0.77 1.51 1.31 1/168 9857
Mon Dec 29 17:27:16 CET 2014 1353.21 9843348.09 stopped xbmc                             0.77 1.51 1.31 8/157 9893
Mon Dec 29 17:27:16 CET 2014 1353.21 9843348.09 starting xbmc-exits                      0.77 1.51 1.31 6/154 9893
Mon Dec 29 17:27:16 CET 2014 1353.22 9843348.09 started xbmc-changesettings              0.77 1.51 1.31 9/157 9893
Mon Dec 29 17:27:16 CET 2014 1353.26 9843348.09 stopped xbian-xbmc-cpufreq               0.77 1.51 1.31 2/148 9898
Mon Dec 29 17:27:16 CET 2014 1353.31 9843348.09 stopped tvonoff-xbmc                     0.77 1.51 1.31 2/146 9904
Mon Dec 29 17:27:17 CET 2014 1354.19 10626285.57 stopped xbmc-changesettings              0.77 1.51 1.31 4/148 9915
Mon Dec 29 17:27:17 CET 2014 1354.26 10626285.57 started xbmc-exits                       0.77 1.51 1.31 2/146 9928
Mon Dec 29 17:27:17 CET 2014 1354.32 10626285.57 starting xbmc                            0.77 1.51 1.31 4/147 9937
Mon Dec 29 17:27:17 CET 2014 1354.37 10626285.57 started xbian-xbmc-cpufreq               0.77 1.51 1.31 3/145 9944
Mon Dec 29 17:27:18 CET 2014 1354.42 10626285.57 stopped xbmc-exits                       0.77 1.51 1.31 4/147 9952
Mon Dec 29 17:27:18 CET 2014 1354.93 10626285.63 started wait-for-state  xbmc-loaded      0.77 1.51 1.31 3/151 9999

Temporary workaround is commenting in /etc/init/xbmc.conf

post-start script
#    /sbin/start -q wait-for-state TIMEOUT=100 WAIT_FOR=xbmc-loaded WAITER=xbmc WAIT_STATE=started ACT=no || { /sbin/initctl emit -n xbmc-failed-start; stop; exit 1; }
CurlyMoo commented 9 years ago

The issue is that somehow the xbmc-loaded event doesn't get emitted by your patch inside XBMC.

mk01 commented 9 years ago

@CurlyMoo

you have to find one more factor in that as look here :+1: (tailf on event log and start xbmc started at the same time, both outputing to screen. logs starts with event "starting xbmc", in 6s xbmc has home screen, comnmand "start xbmc" returns to console as "xbmc-loadeded event was emited by job xbmc-loaded"

root@sona ~ # tailf /var/log/upstart/upstart-ev.log | awk -F'   ' '{print $1}'  & start xbmc
[2] 3231
Wed Dec 31 06:51:42 CET 2014 325.96 148534580.97 starting xbmc                         
Wed Dec 31 06:51:42 CET 2014 326.01 148534580.97 started xbian-xbmc-cpufreq            
Wed Dec 31 06:51:42 CET 2014 326.43 148534580.97 started wait-for-state  xbmc-loaded   
Wed Dec 31 06:51:48 CET 2014 331.86 148534583.69 started xbmc-loaded                 
Wed Dec 31 06:51:48 CET 2014 331.94 148534583.69 stopped wait-for-state  xbmc-loaded    
xbmc start/running, process 3282
root@sona ~ # Wed Dec 31 06:51:48 CET 2014 332.05 148534583.69 started xbmc    
Wed Dec 31 06:51:48 CET 2014 332.20 148534583.69 stopped tvonoff-xbmc             
Wed Dec 31 06:51:55 CET 2014 338.85 148534585.25 stopped xbmc-loaded                 
tailf /var/log/upstart/upstart-ev.log | awk -F'   ' '{print $1}'  & start xbmcWed Dec 31 06:52:02 CET 2014 346.73 148534586.32 stopped xbian-xbmc-cpufreq          
Wed Dec 31 06:52:02 CET 2014 346.75 148534586.32 started xbmc-screensaver   
Wed Dec 31 06:52:03 CET 2014 346.99 148534586.32 started xbmc-priority  
Wed Dec 31 06:52:03 CET 2014 347.14 148534586.32 stopped xbmc-priority  

this is the last img with xbmc-6q updated to 14.0RC3 on HB1. but the same happens with CBi with root originally installed as wheezy (and being like very old rootfs

mk01 commented 9 years ago

what to check quick --if /etc/sudoers.d/xbian is proper file and contains beside others also

/sbin/start
CurlyMoo commented 9 years ago

We know it's working again.