HDR will no longer go into standby

xyz321

Well-Known Member
Last night the Humax got itself into a strrange state where it would not play back files. When attempting to reboot, it would not go into standby. After power cycling it will now playback the problem recordings but still will not enter full standby (it just goes into half awake mode).

I have tried the following with no success:
  • Removed redring
  • Run fix-disk
  • Cleared out the scheduled events
  • Reset to factory defaults
Plus many power cycles along the way. Does anyone have any further suggestions?
 
The only other thing I can think of is that power on / off timers need to be in pairs, e.g. if one gets deleted the Humax goes into 'Halk-Awake'
 
The only other thing I can think of is that power on / off timers need to be in pairs, e.g. if one gets deleted the Humax goes into 'Halk-Awake'
I don't have any power on/off timers set.
Sorry, no ideas.. does lsof show anything in half awake?
Nothing obvious...
Code:
lsof -p 256
COMMAND PID USER   FD   TYPE DEVICE                SIZE        NODE NAME
humaxtv 256 root  cwd   0000   31,0 7019251880541375519 77309428221 /
humaxtv 256 root  rtd   0000   31,0 7019251880541375519 77309428221 /
humaxtv 256 root  txt   0000   31,0 7019251880529558336  4295000512 /usr/bin/humaxtv
humaxtv 256 root  mem    REG   31,0                            1655 /usr/bin/humaxtv (path inode=4295000512)
humaxtv 256 root  mem    REG   31,0                             187 /lib/ld-uClibc-0.9.29.so (path inode=4295000557)
humaxtv 256 root  mem    REG   31,2                             960 /var/lib/humaxtv_backup/mod/libdustbin.so (path inode=429000512)
humaxtv 256 root  mem    REG   31,2                             975 /var/lib/humaxtv_backup/mod/libir3.so (path inode=429500012)
humaxtv 256 root  mem    REG   31,2                             963 /var/lib/humaxtv_backup/mod/libredring.so (path inode=429000512)
humaxtv 256 root  mem    REG   31,0                            1613 /usr/lib/libjpeg.so.62.0.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1606 /usr/lib/libfreetype.so.6.3.18 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1583 /usr/lib/libpng12.so.0.25.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1601 /usr/lib/libz.so.1.2.3 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1624 /usr/lib/libid3-3.8.so.3.0.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             184 /lib/libstdc++.so.6.0.9 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1614 /usr/lib/libexif.so.12.2.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1610 /usr/lib/libblkid.so.1.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1619 /usr/lib/libcom_err.so.2.1 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1585 /usr/lib/libe2p.so.2.3 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1590 /usr/lib/libss.so.2.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1600 /usr/lib/libuuid.so.1.2 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1645 /usr/lib/libext2fs.so.2.4 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1587 /usr/lib/libcurl.so.4.1.1 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1644 /usr/lib/libssl.so.1.0.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1592 /usr/lib/libcrypto.so.1.0.0 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                            1640 /usr/lib/libnexus.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             192 /lib/librt-0.9.29.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             214 /lib/libpthread-0.9.29.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             182 /lib/libuClibc-0.9.29.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             185 /lib/libm-0.9.29.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             199 /lib/libdl-0.9.29.so (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             202 /lib/libgcc_s.so.1 (path inode=4295000448)
humaxtv 256 root  mem    REG   31,0                             460 /dev/mem (path inode=4294975904)
humaxtv 256 root    0   0000   31,0 7019251880541975569  4294975926 /dev/console
humaxtv 256 root    1   0000   0,13 7019251880541975686  4295000484 /tmp/humaxtv.log
humaxtv 256 root    2   0000   0,13 7019251880541975686  4295000484 /tmp/humaxtv.log
humaxtv 256 root    3   0000    0,4 7019251879188103168  4295016959 socket
humaxtv 256 root    4   0000   31,0 7019251880529557799  4294975926 /dev/brcm0
humaxtv 256 root    5   0000   31,0 7019251880529557799  4294975904 /dev/mem
humaxtv 256 root    6   0000   31,0 7019251880529557799  4294975904 /dev/mem
humaxtv 256 root    7   0000   31,0 7019251880541975664  4294975926 /dev/ttyS2
humaxtv 256 root    8   0000   31,0 7019251880541975664  4294975926 /dev/ttyS2
humaxtv 256 root    9   0000   31,0 7019251880529557799  4294975904 /dev/mtd6
humaxtv 256 root   10   0000   31,0 7019251880529557799  4294975904 /dev/mtd3
humaxtv 256 root   11   0000   0,11 7019251880134787979  4295000512 /hackir
humaxtv 256 root   12   0000   0,12 7019251880541975794  4295000484 /proc/bus/usb/001/001
humaxtv 256 root   13   0000   0,12 7019251880541975794  4295000484 /proc/bus/usb/002/001
humaxtv 256 root   14   0000   0,12 7019251880541975794  4295000484 /proc/bus/usb/003/001
humaxtv 256 root   15   0000   0,12 7019251880541975794  4295000484 /proc/bus/usb/004/001
humaxtv 256 root   16   0000   31,1 7019251880541975381  4295000484 /var/lib/humaxtv/rsv.db
humaxtv 256 root   17   0000   31,0 7019251880529557799  4294975904 /dev/hmxsdcon
humaxtv 256 root   18   0000    0,5 7019251880541951875  4294971776 pipe
humaxtv 256 root   19   0000    0,5 7019251880541951875  4294971776 pipe
humaxtv 256 root   20   0000    0,5 7019251880541975686  4294971776 pipe
humaxtv 256 root   21   0000    0,5 7019251880541975686  4294971776 pipe
humaxtv 256 root   22   0000    0,5 7019251880541975686  4294971776 pipe
humaxtv 256 root   23   0000    0,5 7019251880541975686  4294971776 pipe
humaxtv 256 root   24   0000   31,0 7019251880529557799  4294992288 /dev/sdb
humaxtv 256 root   25   0000    0,5 7019251880541951878  4294971776 pipe
humaxtv 256 root   26   0000    0,5 7019251880541951878  4294971776 pipe
humaxtv 256 root   27   0000   8,18 7019251880541975664  4295000484 /mnt/hd2/mod/tmp/redring.log
humaxtv 256 root   34   0000   31,0 7019251880529558129  4295000448 /opt/share/fonts/DejaVuSans.ttf
humaxtv 256 root   35   0000   31,0 7019251880529558129  4295000448 /opt/share/fonts/DejaVuSans.ttf
Here's the end of redring.log when the box should be in standby
Code:
[RR] Sun Nov 25 19:41:32 2012: -> DisplayBrightness(0x19) fc
[RR] Sun Nov 25 19:41:32 2012: +> DisplayBrightness(0x19) fc
[RR] Sun Nov 25 19:41:32 2012: !> DisplayBrightness(0x19) fc
[RR] Sun Nov 25 19:41:32 2012: -> RingBrightness(0x1a) fc
[RR] Sun Nov 25 19:41:32 2012: +> RingBrightness(0x1a) fc
[RR] Sun Nov 25 19:41:32 2012: !> RingBrightness(0x1a) fc
[RR] Sun Nov 25 19:41:33 2012: -> DisplayBrightness(0x19) 1d
[RR] Sun Nov 25 19:41:33 2012: +> DisplayBrightness(0x19) 1d
[RR] Sun Nov 25 19:41:33 2012: !> DisplayBrightness(0x19) 1d
[RR] Sun Nov 25 19:41:33 2012: -> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: Standby ring dim detected.
[RR] Sun Nov 25 19:41:33 2012: +> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: !> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: -> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: Standby ring dim detected.
[RR] Sun Nov 25 19:41:33 2012: +> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: !> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:33 2012: -> ClockControl(0x13) 00
[RR] Sun Nov 25 19:41:33 2012: +> ClockControl(0x13) 00
[RR] Sun Nov 25 19:41:33 2012: !> ClockControl(0x13) 00
[RR] Sun Nov 25 19:41:33 2012: -> ???(0x16) 00
[RR] Sun Nov 25 19:41:33 2012: +> ???(0x16) 00
[RR] Sun Nov 25 19:41:33 2012: !> ???(0x16) 00
[RR] Sun Nov 25 19:41:34 2012: -> RingColour(0xe) 00
[RR] Sun Nov 25 19:41:34 2012: Ring trying to go amber.
[RR] Sun Nov 25 19:41:34 2012:   Changing to dim blue.
[RR] Sun Nov 25 19:41:34 2012: +> RingBrightness(0x1a) 3f
[RR] Sun Nov 25 19:41:34 2012: !> RingBrightness(0x1a) 3f
[RR] Sun Nov 25 19:41:34 2012: +> RingColour(0xe) 01
[RR] Sun Nov 25 19:41:34 2012: !> RingColour(0xe) 01
[RR] Sun Nov 25 19:41:34 2012: -> LEDControl(0x10) 00 00 01 00
[RR] Sun Nov 25 19:41:34 2012:    [ TV = OFF ]
[RR] Sun Nov 25 19:41:34 2012: +> LEDControl(0x10) 00 00 01 00
[RR] Sun Nov 25 19:41:34 2012:    [ TV = OFF ]
[RR] Sun Nov 25 19:41:34 2012: !> LEDControl(0x10) 00 00 01 00
[RR] Sun Nov 25 19:41:34 2012:    [ TV = OFF ]
[RR] Sun Nov 25 19:41:34 2012: -> LEDControl(0x10) 00 00 08 00
[RR] Sun Nov 25 19:41:34 2012:    [ HD = OFF ]
[RR] Sun Nov 25 19:41:34 2012: +> LEDControl(0x10) 00 00 08 00
[RR] Sun Nov 25 19:41:34 2012:    [ HD = OFF ]
[RR] Sun Nov 25 19:41:34 2012: !> LEDControl(0x10) 00 00 08 00
[RR] Sun Nov 25 19:41:34 2012:    [ HD = OFF ]
[RR] Sun Nov 25 19:41:34 2012: -> LEDControl(0x10) 00 00 04 00
[RR] Sun Nov 25 19:41:34 2012:    [ FULL = OFF ]
[RR] Sun Nov 25 19:41:34 2012: +> LEDControl(0x10) 00 00 04 00
[RR] Sun Nov 25 19:41:34 2012:    [ FULL = OFF ]
[RR] Sun Nov 25 19:41:34 2012: !> LEDControl(0x10) 00 00 04 00
[RR] Sun Nov 25 19:41:34 2012:    [ FULL = OFF ]
[RR] Sun Nov 25 19:41:34 2012: -> LEDControl(0x10) 00 80 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 1 = OFF ]
[RR] Sun Nov 25 19:41:34 2012: +> LEDControl(0x10) 00 80 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 1 = OFF ]
[RR] Sun Nov 25 19:41:34 2012: !> LEDControl(0x10) 00 80 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 1 = OFF ]
[RR] Sun Nov 25 19:41:34 2012: -> LEDControl(0x10) 01 00 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 2 = OFF ]
[RR] Sun Nov 25 19:41:34 2012: +> LEDControl(0x10) 01 00 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 2 = OFF ]
[RR] Sun Nov 25 19:41:34 2012: !> LEDControl(0x10) 01 00 00 00
[RR] Sun Nov 25 19:41:34 2012:    [ Disk space 2 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: -> LEDControl(0x10) 02 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 3 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: +> LEDControl(0x10) 02 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 3 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: !> LEDControl(0x10) 02 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 3 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: -> LEDControl(0x10) 04 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 4 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: +> LEDControl(0x10) 04 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 4 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: !> LEDControl(0x10) 04 00 00 00
[RR] Sun Nov 25 19:41:35 2012:    [ Disk space 4 = OFF ]
[RR] Sun Nov 25 19:41:35 2012: -> SetClock(0x3) 50 b2 74 6f
[RR] Sun Nov 25 19:41:35 2012:    [ Sun Nov 25 19:41:35 2012 ]  ( 1353872495 )
[RR] Sun Nov 25 19:41:35 2012: +> SetClock(0x3) 50 b2 74 6f
[RR] Sun Nov 25 19:41:35 2012:    [ Sun Nov 25 19:41:35 2012 ]  ( 1353872495 )
[RR] Sun Nov 25 19:41:35 2012: !> SetClock(0x3) 50 b2 74 6f
[RR] Sun Nov 25 19:41:35 2012:    [ Sun Nov 25 19:41:35 2012 ]  ( 1353872495 )
[RR] Sun Nov 25 19:41:35 2012: -> SetWakeup(0x5) 01 50 b2 83 10
[RR] Sun Nov 25 19:41:35 2012:    [ Sun Nov 25 20:44:00 2012 ]  ( 1353876240 )
[RR] Sun Nov 25 19:41:36 2012: -> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:36 2012: Standby ring dim detected.
[RR] Sun Nov 25 19:41:36 2012: +> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:41:36 2012: !> RingBrightness(0x1a) 1d
[RR] Sun Nov 25 19:45:11 2012: -> LEDControl(0x10) 00 80 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 1 = ON ]
[RR] Sun Nov 25 19:45:11 2012: +> LEDControl(0x10) 00 80 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 1 = ON ]
[RR] Sun Nov 25 19:45:11 2012: !> LEDControl(0x10) 00 80 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 1 = ON ]
[RR] Sun Nov 25 19:45:11 2012: -> LEDControl(0x10) 01 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 2 = ON ]
[RR] Sun Nov 25 19:45:11 2012: +> LEDControl(0x10) 01 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 2 = ON ]
[RR] Sun Nov 25 19:45:11 2012: !> LEDControl(0x10) 01 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 2 = ON ]
[RR] Sun Nov 25 19:45:11 2012: -> LEDControl(0x10) 02 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 3 = ON ]
[RR] Sun Nov 25 19:45:11 2012: +> LEDControl(0x10) 02 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 3 = ON ]
[RR] Sun Nov 25 19:45:11 2012: !> LEDControl(0x10) 02 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 3 = ON ]
[RR] Sun Nov 25 19:45:11 2012: -> LEDControl(0x10) 04 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 4 = ON ]
[RR] Sun Nov 25 19:45:11 2012: +> LEDControl(0x10) 04 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 4 = ON ]
[RR] Sun Nov 25 19:45:11 2012: !> LEDControl(0x10) 04 00 00 01
[RR] Sun Nov 25 19:45:11 2012:    [ Disk space 4 = ON ]
 
The disk space indicators going off then coming back on 4 minutes later is exactly what happens when the box stays half awake because there's a recording due. Definitely seems to be something the software is deciding to do!
 
Found the problem - it was caused by a zero length ts file with associated non-zero length nts and hmt files.
 
Found the problem - it was caused by a zero length ts file with associated non-zero length nts and hmt files.

I realise the 'half-awake' state was something that you was trying to get rid of, but I wonder if this could be used to force an 'half-awake' state as requested HERE
 
Sounds like it may be worth looking into and will try to reproduce it, but maybe not so easy to manage as 1x wake & 2x sleep via RS.
For instance... Would it still make recordings with this problem? Does the box need a reboot to recover?
 
I have found HDR3 in the delinquent half-awake state this morning - it has timers set to start up at 6pm and go off at 3am, but last night obviously didn't complete properly. I will investigate why this might be, but I was playing around with some very short recordings yesterday and decryption had a hiccup. Powering on and off again has not taken it back to standby.
 
Not got to the bottom of it yet, but I have found an orphaned .htm and .thm created a week ago and orphaned last night - note please lines highlighted in the empty_dustbin.log extract below:

Code:
>>> Contents of /mod/tmp/empty_dustbin.log 63.48 KiB
...
Emptying dustbin at Tue Apr 23 02:06:01 BST 2013
Dustbin: /mnt/hd2/My Video/[Recycle Bin]
Processing dustbin... (7 - 10,20,40,60,80,100,120)
Keeping deleted files for 7 days.
./[NEWS]/BBC News at Six_20130411_1759.hmt
./[NEWS]/BBC News at Six_20130411_1759.nts
./[NEWS]/BBC Wales Today_20130411_1829.ts
./[NEWS]/BBC Wales Today_20130411_1829.thm
./[NEWS]/BBC News at Six_20130411_1759.thm
./[NEWS]/BBC Wales Today_20130411_1829.hmt
./[NEWS]/BBC News at Six_20130411_1759.ts
./[NEWS]/BBC Points West_20130411_1830.nts
./[NEWS]/BBC Wales Today_20130411_1829.nts
./[NEWS]/BBC Points West_20130411_1830.ts
./[NEWS]/BBC Points West_20130411_1830.thm
./[NEWS]/BBC Points West_20130411_1830.hmt
./webif_autodecrypt/[NEWS]/BBC Wales Today_20130415_1829.hmt
./webif_autodecrypt/[NEWS]/BBC News at Six_20130415_1759.nts
./webif_autodecrypt/[NEWS]/BBC Points West_20130415_1830.ts
./webif_autodecrypt/[NEWS]/BBC Wales Today_20130415_1829.ts
./webif_autodecrypt/[NEWS]/BBC News at Six_20130415_1759.hmt
./webif_autodecrypt/[NEWS]/BBC News at Six_20130415_1759.ts
./webif_autodecrypt/[NEWS]/BBC Points West_20130415_1830.hmt
./webif_autodecrypt/[NEWS]/BBC Wales Today_20130415_1829.nts
./webif_autodecrypt/[NEWS]/BBC Points West_20130415_1830.nts
./webif_autodecrypt/Bang Goes the Theory_20130415_1928.nts        *****
./webif_autodecrypt/Bang Goes the Theory_20130415_1928.ts         *****
1 = 10, 2 = 20, 3 = 40, 4 = 60, 5 = 80, 6 = 100, 7 = 120, 
Free disk space: 245.886 GiB
Removing empty directories...
Looking for orphaned HMT files.
======================================================================

It appears to me that the orphaning has occurred because the creation time for the files has straddled the deadline for recycle bin emptying. Wouldn't it be better to delete file sets as a whole rather than piecemeal? What does "Looking for orphaned HMT files" mean ('cos it does not appear to have been successful)?

PS: I have noticed the Humax menu does not loose its place when resuming from half-awake.
 
I have tried deleting the orphaned files (via network share); turning off, resetting, and turning on again the DLNA database - but still HDR3 is half-awake. No time to pursue it further now.
 
Maybe it's got insomnia? Or maybe it was watching a horror movie and got so scared that it can't sleep....


Hmmmm. Beef with Apple. Sounds delicious.
 
When I had the same problem it was an unplayable file. Using the remote control, I worked down my recordings list (folders , single recordings and [Deleted]) and pressed opt+ on each until I found a folder where move/copy was greyed out. I opened that folder and repeated the operation on each file until I found the corrupt file. I then deleted it, although it has been reported that moving the file works just as well.
 
I had Directory Opus (a File Explorer replacement) working over SMB and was looking at the sizes - none were unexpectedly small.

Update: I tried your find command too - no match.
 
When I had the same problem it was an unplayable file. Using the remote control, I worked down my recordings list (folders , single recordings and [Deleted]) and pressed opt+ on each until I found a folder where move/copy was greyed out. I opened that folder and repeated the operation on each file until I found the corrupt file. I then deleted it, although it has been reported that moving the file works just as well.
Thanks for the suggestion but nothing there either.
 
Interesting: a forced reboot (diagnostics) from delinquent half-awake shuts it down, but powering up again and returning to standby resumes the delinquent state. I am no nearer working out what is causing it.
 
Tried a cold boot, no change. As a non-destructive option I decided to go into maintenance mode and try running fix-disk, but it seems to bomb out:

Code:
Checking partition /dev/sdb2...
e2fsck 1.41.14 (22-Dec-2010)
Pass 1: Checking inodes, blocks, and sizes
hmx_int_stor: |                                                |  0.1%   
hmx_int_stor: |                                                /  0.2%

Sorry, spoke too soon - it is incrementing now but it took a long time to move from 0.2%.
 
Back
Top