Humax hung but telnet still active

MymsMan

Ad detector
I have just experienced a Humax hang where it is not responding to the remote or front power button but I am still able to access the command line via PuTTY so I was wondering if there was anything that I could do to collect any useful diagnostics and/or unfreeze it without resorting to back power switch?

Nothing related to customised firmware appears to be running, no active recordings and HumaxTV process is only consuming moderate cpu
Code:
top - 13:36:03 up 7 min,  0 users,  load average: 8.68, 5.84, 2.69
Tasks:  59 total,   1 running,  57 sleeping,   0 stopped,   1 zombie
Cpu(s):  4.6%us,  7.9%sy, 15.3%ni, 72.0%id,  0.0%wa,  0.0%hi,  0.2%si,  0.0%st
Mem:    125016k total,   120956k used,     4060k free,    38228k buffers
Swap:   131064k total,       24k used,   131040k free,    20268k cached

  PID USER      PR  NI  VIRT  RES  SHR S %CPU %MEM    TIME+  COMMAND
  246 root      18   0  330m  32m  11m S   15 26.6   0:49.37 humaxtv
 
Correction - there are some CF processes in the list, some related to my attempts to use the webif but why are S90settop and autoschedule active
Code:
Humax# ps -Af
UID        PID  PPID  C STIME TTY          TIME CMD
root         1     0  0 13:28 ?        00:00:02 init
root         2     1  0 13:28 ?        00:00:00 [migration/0]
root         3     1  0 13:28 ?        00:00:00 [ksoftirqd/0]
root         4     1  0 13:28 ?        00:00:00 [migration/1]
root         5     1  0 13:28 ?        00:00:00 [ksoftirqd/1]
root         6     1  0 13:28 ?        00:00:00 [events/0]
root         7     1  0 13:28 ?        00:00:00 [events/1]
root         8     1  0 13:28 ?        00:00:00 [khelper]
root         9     1  0 13:28 ?        00:00:00 [kthread]
root        10     9  0 13:28 ?        00:00:00 [kblockd/0]
root        11     9  0 13:28 ?        00:00:00 [kblockd/1]
root        12     9  0 13:28 ?        00:00:00 [khubd]
root        13     9  0 13:28 ?        00:00:00 [kseriod]
root        14     9  0 13:28 ?        00:00:00 [pdflush]
root        15     9  0 13:28 ?        00:00:00 [pdflush]
root        16     9  0 13:28 ?        00:00:00 [kswapd0]
root        17     9  0 13:28 ?        00:00:00 [aio/0]
root        18     9  0 13:28 ?        00:00:00 [aio/1]
root        19     9  0 13:28 ?        00:00:00 [ata/0]
root        20     9  0 13:28 ?        00:00:00 [ata/1]
root        21     9  0 13:28 ?        00:00:00 [ata_aux]
root        22     9  0 13:28 ?        00:00:00 [scsi_eh_0]
root        23     9  0 13:28 ?        00:00:00 [scsi_eh_1]
root        24     1  0 13:28 ?        00:00:04 [mtdblockd]
root        25     9  0 13:28 ?        00:00:00 [kpsmoused]
root        26     1  0 13:28 ?        00:00:00 /bin/sh /etc/init.d/rcS
root        71     1  0 13:28 ?        00:00:00 [jffs2_gcd_mtd1]
root        72     1  0 13:28 ?        00:00:00 [jffs2_gcd_mtd2]
nobody     135     1  0 13:28 ?        00:00:00 /usr/bin/dnsmasq
root       141     1  0 13:28 ?        00:00:00 /sbin/utelnetd -l /bin/sh -p 23 -d -b
root       186    26  0 13:28 ?        00:00:00 /bin/sh /etc/init.d/S90settop start
root       246   186  8 13:28 ?        00:02:29 /usr/bin/humaxtv
root       405     1  0 13:28 ?        00:00:00 udhcpc -t 5 -T 10 -p /var/lib/humaxtv/udhcpc.eth0.pid -i eth0
root       439     9  0 13:28 ?        00:00:00 [kjournald]
root       466     1  0 13:28 ?        00:00:00 /bin/sh /etc/mdev/run-and-gun
root       573     9  0 13:28 ?        00:00:00 [kjournald]
root       575     9  0 13:28 ?        00:00:00 [kjournald]
root       618   466  0 13:28 ?        00:00:00 /bin/sh /sbin/modinit
root       740   618  0 13:28 ?        00:00:00 /bin/sh /etc/init.d/rcS.mod
root       774     9  0 13:28 ?        00:00:00 [cifsoplockd]
root       775     9  0 13:28 ?        00:00:00 [cifsdnotifyd]
root       786     1  0 13:28 ?        00:00:00 /mod/sbin/crond
root       812     1  0 13:28 ?        00:00:00 /mod/sbin/lighttpd -f /mod/etc/lighttpd/server.conf
root       813   812  0 13:28 ?        00:00:00 /mod/sbin/lighttpd -f /mod/etc/lighttpd/server.conf
root       815   812  0 13:28 ?        00:00:00 /mod/sbin/lighttpd -f /mod/etc/lighttpd/server.conf
daemon     838     1  0 13:28 ?        00:00:00 /mod/sbin/portmap
root       845   740  0 13:28 ?        00:00:00 /bin/sh /mod/etc/init.d/S03autoschedule start
root       846   845  0 13:28 ?        00:00:00 /mod/bin/jimsh /mod/sbin/autoschedule
root      1595   815  0 13:31 ?        00:00:00 /mod/bin/jimsh /mod/webif/cgi-bin/status.jim
root      1605   815  0 13:31 ?        00:00:00 /mod/bin/jimsh /mod/webif/html/browse/index.jim
root      1638   813  0 13:32 ?        00:00:00 /mod/bin/jimsh /mod/webif/cgi-bin/status.jim
root      1643   813  0 13:32 ?        00:00:00 /mod/bin/jimsh /mod/webif/cgi-bin/xepg.jim
root      1657   141  0 13:32 pts/0    00:00:00 -/bin/sh
root      1852   815  0 13:40 ?        00:00:00 /mod/bin/jimsh /mod/webif/cgi-bin/xepg.jim
root      2685  1657  0 13:57 pts/0    00:00:00 ps -Af
 
It's normal to have S90settop active but autoschedule wouldn't normally hang around (not the parent processes of run-and-gun and rcS)
 
Code:
Humax# lsof -p 846
COMMAND   PID USER   FD   TYPE DEVICE    SIZE     NODE NAME
autosched 846 root  cwd    DIR   31,0    2140       31 /dev
autosched 846 root  rtd    DIR   31,0     183       89 /
autosched 846 root  txt    REG    8,2  406972 18690317 /mnt/hd2/mod/bin/jimsh
autosched 846 root  mem    REG   31,0   35395      166 /lib/ld-uClibc-0.9.29.so
autosched 846 root  mem    REG   31,0   11629      178 /lib/libdl-0.9.29.so
autosched 846 root  mem    REG   31,0  837630      161 /lib/libuClibc-0.9.29.so
autosched 846 root  mem    REG    8,2   10828 18692912 /mnt/hd2/mod/lib/jim/sqlite3.so
autosched 846 root  mem    REG    8,2 1446004 18689742 /mnt/hd2/mod/lib/libsqlite3.so.0.8.6
autosched 846 root  mem    REG   31,0  109417      193 /lib/libpthread-0.9.29.so
autosched 846 root    0r   CHR    1,3              330 /dev/null
autosched 846 root    1w   REG   0,13    9732     1200 /tmp/modinit.log
autosched 846 root    2w   REG   0,13    9732     1200 /tmp/modinit.log
autosched 846 root    3r   REG   31,0     209      211 /etc/mdev.conf
autosched 846 root    4u   REG    8,2    5120 18689628 /mnt/hd2/mod/etc/webif.db
autosched 846 root    5u   REG   31,1   26624      108 /var/lib/humaxtv/rsv.db
autosched 846 root    6u   REG   31,1   61440    58057 /var/lib/humaxtv/channel.db
autosched 846 root    7u   REG   31,1   21504     2379 /var/lib/humaxtv/rsvp.db
autosched 846 root    8u   REG   31,1   61440    58057 /var/lib/humaxtv/channel.db
autosched 846 root    9u   REG    8,1 6495232       11 /mnt/hd1/epg.db
Humax#
 
modinit.log - there was a burst of activity after I killed S03autoschedule but I think it is time to resort to the big switch before the 3pm recordings are due
Code:
Humax# cat /tmp/modinit.log
=== Fri Nov 13 13:28:23 UTC 2015 - modinit - setup_hosts ===========================
=== Fri Nov 13 13:28:23 UTC 2015 - setup hosts ===========================
Kernel IP routing table
Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
192.168.1.0     *               255.255.255.0   U     0      0        0 eth0
default         192.168.1.254   0.0.0.0         UG    0      0        0 eth0
[RR] Fri Nov 13 13:28:23 2015: Recording end 0.
-------------------------------------
Initialising Dustbin v1.0
Dustbin debug: 0
Dustbin media root: /mnt/hd2/My Video/
Dustbin path: /mnt/hd2/My Video/[Deleted Items]
-------------------------------------
[RR] Fri Nov 13 13:28:23 2015: Recording end 0.
    Interface: [eth0]
[RR] Fri Nov 13 13:28:23 2015: Recording end 0.
-------------------------------------
Initialising Dustbin v1.0
Dustbin debug: 0
Dustbin media root: /mnt/hd2/My Video/
Dustbin path: /mnt/hd2/My Video/[Deleted Items]
-------------------------------------
[RR] Fri Nov 13 13:28:23 2015: Recording end 0.
    IP: [192.168.1.2]
=== Fri Nov 13 13:28:24 UTC 2015 - complete ===========================
=== Fri Nov 13 13:28:34 UTC 2015 - modinit - ===========================
=== Fri Nov 13 13:28:34 UTC 2015 - diag ===========================
Filesystem                Size      Used Available Use% Mounted on
/dev/root                21.3M     21.3M         0 100% /
tmpfs                    61.0M     52.0K     61.0M   0% /tmp
tmpfs                    61.0M         0     61.0M   0% /media
/dev/mtdblock1            2.0M    696.0K      1.3M  34% /var/lib/humaxtv
/dev/mtdblock2            2.0M    444.0K      1.6M  22% /var/lib/humaxtv_backup
/dev/mtdblock2            2.0M    444.0K      1.6M  22% /usr/browser/opera_dir/url/url.txt
/dev/sda1              1011.4M     44.3M    915.8M   5% /mnt/hd1
/dev/sda2               447.6G    375.1G     49.7G  88% /mnt/hd2
/dev/sda3                 9.8G      3.1G      6.3G  33% /mnt/hd3

eth0      Link encap:Ethernet  HWaddr DC:D3:21:57:A5:F3
          inet addr:192.168.1.2  Bcast:192.168.1.255  Mask:255.255.255.0
          UP BROADCAST RUNNING MULTICAST  MTU:1500  Metric:1
          RX packets:2 errors:0 dropped:0 overruns:0 frame:0
          TX packets:2 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:1000
          RX bytes:656 (656.0 B)  TX bytes:664 (664.0 B)
          Interrupt:16

lo        Link encap:Local Loopback
          inet addr:127.0.0.1  Mask:255.0.0.0
          UP LOOPBACK RUNNING  MTU:16436  Metric:1
          RX packets:0 errors:0 dropped:0 overruns:0 frame:0
          TX packets:0 errors:0 dropped:0 overruns:0 carrier:0
          collisions:0 txqueuelen:0
          RX bytes:0 (0.0 B)  TX bytes:0 (0.0 B)


  PID USER       VSZ STAT COMMAND
    1 root      1244 S    init
    2 root         0 SW   [migration/0]
    3 root         0 SWN  [ksoftirqd/0]
    4 root         0 SW   [migration/1]
    5 root         0 SWN  [ksoftirqd/1]
    6 root         0 SW<  [events/0]
    7 root         0 SW<  [events/1]
    8 root         0 SW<  [khelper]
    9 root         0 SW<  [kthread]
   10 root         0 SW<  [kblockd/0]
   11 root         0 SW<  [kblockd/1]
   12 root         0 SW<  [khubd]
   13 root         0 SW<  [kseriod]
   14 root         0 SW   [pdflush]
   15 root         0 DW   [pdflush]
   16 root         0 DW<  [kswapd0]
   17 root         0 SW<  [aio/0]
   18 root         0 SW<  [aio/1]
   19 root         0 SW<  [ata/0]
   20 root         0 SW<  [ata/1]
   21 root         0 SW<  [ata_aux]
   22 root         0 SW<  [scsi_eh_0]
   23 root         0 SW<  [scsi_eh_1]
   24 root         0 SW   [mtdblockd]
   25 root         0 SW<  [kpsmoused]
   26 root      1236 S    {rcS} /bin/sh /etc/init.d/rcS
   71 root         0 SWN  [jffs2_gcd_mtd1]
   72 root         0 SWN  [jffs2_gcd_mtd2]
   86 root       972 S    /sbin/bootstrapd init.html
  135 nobody     936 S    /usr/bin/dnsmasq
  141 root       812 S N  /sbin/utelnetd -l /bin/sh -p 23 -d -b
  186 root      1252 S    {S90settop} /bin/sh /etc/init.d/S90settop start
  246 root      327m S    /usr/bin/humaxtv
  405 root      1688 S    udhcpc -t 5 -T 10 -p /var/lib/humaxtv/udhcpc.eth0.pid -i eth0
  439 root         0 SW<  [kjournald]
  466 root      1260 S <  {run-and-gun} /bin/sh /etc/mdev/run-and-gun
  471 root      1260 S <  {run-and-gun} /bin/sh /etc/mdev/run-and-gun
  481 root      1260 S <  {run-and-gun} /bin/sh /etc/mdev/run-and-gun
  573 root         0 SW<  [kjournald]
  575 root         0 SW<  [kjournald]
  583 root      1228 S <  sleep 1
  618 root      1252 S <  {modinit} /bin/sh /sbin/modinit
  631 root      1236 R <  ps -w
  626 root      1260 R <  {run-and-gun} /bin/sh /etc/mdev/run-and-gun

Kernel IP routing table
Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
192.168.1.0     *               255.255.255.0   U     0      0        0 eth0
default         dsldevice.lan   0.0.0.0         UG    0      0        0 eth0

=== Fri Nov 13 13:28:34 UTC 2015 - init ===========================
=== Fri Nov 13 13:28:34 UTC 2015 - reset all ===========================
=== Fri Nov 13 13:28:34 UTC 2015 - rma ===========================
=== Fri Nov 13 13:28:34 UTC 2015 - cleanup ===========================
=== Fri Nov 13 13:28:35 UTC 2015 - ugprade bootmod ===========================
=== Fri Nov 13 13:28:35 UTC 2015 - setup directories ===========================
=== Fri Nov 13 13:28:35 UTC 2015 - create modenv ===========================
=== Fri Nov 13 13:28:36 UTC 2015 - migrate bootmod ===========================
=== Fri Nov 13 13:28:36 UTC 2015 - setup hostname ===========================
=== Fri Nov 13 13:28:36 UTC 2015 - setup hosts ===========================
Kernel IP routing table
Destination     Gateway         Genmask         Flags Metric Ref    Use Iface
192.168.1.0     *               255.255.255.0   U     0      0        0 eth0
default         dsldevice.lan   0.0.0.0         UG    0      0        0 eth0
    Interface: [eth0]
    IP: [192.168.1.2]
=== Fri Nov 13 13:28:36 UTC 2015 - run init ===========================
killall: bootstrapd: no process killed
Setting up swapspace version 1, size = 134213632 bytes
UUID=0a5ff1b4-8702-489f-98a2-e0207833e6ae
    Fri Nov 13 13:28:36 UTC 2015 - S00swapper (elapsed: 1s)
Loaded CIFS module into kernel.
    Fri Nov 13 13:28:37 UTC 2015 - S01cifs (elapsed: 0s)
    Fri Nov 13 13:28:37 UTC 2015 - S01crond (elapsed: 0s)
    Fri Nov 13 13:28:37 UTC 2015 - S01lighttpd (elapsed: 1s)
Anacron 2.3 started on 2015-11-13
    Fri Nov 13 13:28:38 UTC 2015 - S02anacron (elapsed: 0s)
    Fri Nov 13 13:28:38 UTC 2015 - S02portmap (elapsed: 0s)
Normal exit (0 jobs run)
Terminated
    Fri Nov 13 13:28:38 UTC 2015 - S03autoschedule (elapsed: 2935s)
    Fri Nov 13 14:17:33 UTC 2015 - S10screensaver (elapsed: 0s)
    Fri Nov 13 14:17:33 UTC 2015 - S30posttvcrash (elapsed: 0s)
    Fri Nov 13 14:17:33 UTC 2015 - S40ir (elapsed: 0s)
Starting recmon...
    Fri Nov 13 14:17:33 UTC 2015 - S54recmon (elapsed: 0s)
    Fri Nov 13 14:17:33 UTC 2015 - S55rs (elapsed: 0s)
Rotating at 102400, keeping 5

-- Rotate

[recmon.20151030041731.log]
    already rotated.
[activity.20151106154103.log]
    already rotated.
[detectads.20151110041744.log]
    already rotated.
[auto.20151112041746.log]
    already rotated.
[activity.20150818201235.log]
    already rotated.
[empty_dustbin.20150804114230.log]
    already rotated.
[tvdiary.20151110041744.log]
    already rotated.
[rs.log]
[recmon.20151102004233.log]
    already rotated.
[auto.20151112145238.log]
    already rotated.
[auto.log]
    -> auto.20151113141733.log
[empty_dustbin.20150909041739.log]
    already rotated.
[tvdiary.20151109004234.log]
    already rotated.
[activity.log]
[detectads.20151112041746.log]
    already rotated.
[recmon.log]
[detectads.log]
    -> detectads.20151113141733.log
[crash.log]
[redring.20151006041731.log]
    already rotated.
[redring.20151103162558.log]
    already rotated.
[tvdiary.20151113041734.log]
    already rotated.
[empty_dustbin.20151017125849.log]
    already rotated.
[chaseget.20151016164229.log]
    already rotated.
[recmon.20151110100906.log]
    already rotated.
[detectads.20151112145238.log]
    already rotated.
[empty_dustbin.20150706113736.log]
    already rotated.
[redring.20151113041734.log]
    already rotated.
[activity.20150518095356.log]
    already rotated.
[redring.20150921133658.log]
    already rotated.
[auto.20151113041734.log]
    already rotated.
[rs.20150610140157.log]
    already rotated.
[chaseget.20151106145240.log]
    already rotated.
[dafail.log]
[tvdiary.20151111073239.log]
    already rotated.
[chaseget.log]
[detectads.20151111041735.log]
    already rotated.
[auto.20151112101233.log]
    already rotated.
[tvdiary.20151112073234.log]
    already rotated.
[auto.20151113101235.log]
    already rotated.
[redring.log]
[chaseget.20150926041731.log]
    already rotated.
[recmon.20151106041734.log]
    already rotated.
[empty_dustbin.log]
[empty_dustbin.20151103071731.log]
    already rotated.
[tvdiary.log]
[redring.20151020202228.log]
    already rotated.
[recmon.20151024041734.log]
    already rotated.
[detectads.20151113041734.log]
    already rotated.

-- Purge

[rs.log] - 1
[auto.log] - 6
  Deleting old log auto.20151112041746.log
[activity.log] - 3
[recmon.log] - 5
[detectads.log] - 6
  Deleting old log detectads.20151110041744.log
[crash.log] - 0
[dafail.log] - 0
[chaseget.log] - 3
[redring.log] - 5
[empty_dustbin.log] - 5
[tvdiary.log] - 5
    Fri Nov 13 14:17:33 UTC 2015 - S59webif (elapsed: 1s)
    Fri Nov 13 14:17:34 UTC 2015 - S60parseepg (elapsed: 0s)
    Fri Nov 13 14:17:34 UTC 2015 - S88dropbear (elapsed: 0s)
rs: offering EPG...
No EPG data to send.
 
Humax restarted normally after rear switch off.

Would it be better to append to the the modinit.log rather than overwrite and 'rotate' it at end of initialisation so that the log of a failed startup is not destroyed by the subsequent successful one
 
Back
Top