Bug 15152 - Continuous playing of same playlist eventually results in reboot
: Continuous playing of same playlist eventually results in reboot
Status: CLOSED FIXED
Product: SqueezePlay
Classification: Unclassified
Component: Audio
: 7.4.x
: Other All
: P1 major (vote)
: 7.5.0
Assigned To: Unassigned bug - please assign me!
:
Depends on:
Blocks: 14489
  Show dependency treegraph
 
Reported: 2009-11-20 09:37 UTC by Mickey Gee
Modified: 2010-04-08 17:26 UTC (History)
6 users (show)

See Also:
Category: Bug


Attachments
Messages log after reboot. (165.34 KB, application/octet-stream)
2009-11-20 09:37 UTC, Mickey Gee
Details
serial log (224.80 KB, text/plain)
2009-12-08 08:07 UTC, James Richardson
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Mickey Gee 2009-11-20 09:37:21 UTC
Created attachment 6331 [details]
Messages log after reboot.

I started playing an album using TinySC. The tracks are on an attached USB hard
drive (FAT format). I put the Touch on playlist repeat. When I did this, I let
it run overnight. The next morning, Touch has rebooted.

From what I can guess from the Uptime field on the Diagnostics screen, the
tracks played in repeat mode for about 4 hours before rebooting.

It's happened a few times with different versions of firmware. Latest one
experiencing this issue is r8121. I believe it's pretty easy to reproduce.

Enclosing messages log after reboot. Probably not super helpful.
Comment 1 Michael Herger 2009-12-04 00:46:56 UTC
QA to reproduce
Comment 2 Michael Herger 2009-12-04 13:30:25 UTC
FWIW: my fab4 has repeatedly been playing my playlist for 12h (r8176). Didn't reboot nor does the slimserver task show any growth in memory usage (constantly about 44M, 36%)
Comment 3 Mickey Gee 2009-12-04 14:37:25 UTC
With firmware r8181, I was able to repeat my playlist (a single album) for almost 15 hours before it rebooted spontaneously.
Comment 4 Michael Herger 2009-12-04 21:55:43 UTC
20h... still going strong

anything special about your playlist?
Comment 5 Mickey Gee 2009-12-04 23:50:08 UTC
My playlist is always a single album. One time is was the Beatles "1" album. Latest one was 2002 "Land of Forever". However, my TinySC db is a 120GB USB drive with around 7000-9000 tracks. It's plugged into a USB hub which is plugged into the USB port.
Comment 6 James Richardson 2009-12-07 08:38:23 UTC
Codec type for your music WMA , MP3 , FLAC , ALAC ....

How are you staring the play list

Make/Model of USB HUB

Is the USB Hub Active or Passive (powered)

Make/Model of USB Storage Device
Comment 7 Mickey Gee 2009-12-07 11:16:03 UTC
I am using flac files for this test. Running with my Toshiba 120GB USB drive. The power plug is not used on my USB drive.

USB hub is StarTech 4 Port USB hub ST4100USB. Its power cable is plugged into AC.

To start playing, I go to My Music->Artists->The Beatles->The Beatles 1->Love Me Do. The default repeat mode is to repeat playlist, so at this point I let it run.

Latest test with r8181 firmware failed after 13 hours.
Comment 8 James Richardson 2009-12-08 08:07:38 UTC
Created attachment 6368 [details]
serial log

See attached message log, I have watchdog disabled, so the unit does not reboot.  Notice in the log watchdog error at Dec  7 17:00:08
===============================================================
Dec  7 17:00:08 squeezeplay: INFO   audio.decode - Playback.lua:423 connect 172.18.79.182:9000 GET /stream.mp3?player=00:04:20:22:00:9e HTTP/1.0^M
NETDEV WATCHDOG: wlan0: transmit timed out
------------[ cut here ]------------
WARNING: at net/sched/sch_generic.c:226 dev_watchdog+0xc0/0x148()
Modules linked in: sd8686
[<c02fb2b4>] (dump_stack+0x0/0x14) from [<c004e710>] (warn_on_slowpath+0x4c/0x68)
[<c004e6c4>] (warn_on_slowpath+0x0/0x68) from [<c0290b0c>] (dev_watchdog+0xc0/0x148)
 r6:c703d1c4 r5:c0427bcc r4:c703d000
[<c0290a4c>] (dev_watchdog+0x0/0x148) from [<c0058bd4>] (run_timer_softirq+0x254/0x2fc)
 r7:c0290a4c r6:c7c2e000 r5:c04107c0 r4:00000000
[<c0058980>] (run_timer_softirq+0x0/0x2fc) from [<c0053ffc>] (ksoftirqd+0x1d0/0x2e8)
[<c0053e2c>] (ksoftirqd+0x0/0x2e8) from [<c0064024>] (kthread+0x54/0x80)
[<c0063fd0>] (kthread+0x0/0x80) from [<c0052270>] (do_exit+0x0/0x670)
 r5:00000000 r4:00000000
---[ end trace 21a3081378dd7793 ]---
Dec  7 17:00:17 squeezeplay: audio_thread_execute:798 xrun (snd_pcm_wait)
Dec  7 17:00:17 squeezeplay: audio_thread_execute:752 underrun!!! (at least 1808127691.056 ms long)
Dec  7 17:00:17 kernel: NETDEV WATCHDOG: wlan0: transmit timed out
Dec  7 17:00:17 kernel: ------------[ cut here ]------------
Dec  7 17:00:17 kernel: WARNING: at net/sched/sch_generic.c:226 dev_watchdog+0xc0/0x148()
Dec  7 17:00:17 kernel: Modules linked in: sd8686
Dec  7 17:00:17 kernel: [<c02fb2b4>] (dump_stack+0x0/0x14) from [<c004e710>] (warn_on_slowpath+0x4c/0x68)
Dec  7 17:00:17 kernel: [<c004e6c4>] (warn_on_slowpath+0x0/0x68) from [<c0290b0c>] (dev_watchdog+0xc0/0x148)
Dec  7 17:00:17 kernel:  r6:c703d1c4 r5:c0427bcc r4:c703d000
Dec  7 17:00:17 kernel: [<c0290a4c>] (dev_watchdog+0x0/0x148) from [<c0058bd4>] (run_timer_softirq+0x254/0x2fc)
Dec  7 17:00:17 kernel:  r7:c0290a4c r6:c7c2e000 r5:c04107c0 r4:00000000
Dec  7 17:00:17 kernel: [<c0058980>] (run_timer_softirq+0x0/0x2fc) from [<c0053ffc>] (ksoftirqd+0x1d0/0x2e8)
Dec  7 17:00:17 kernel: [<c0053e2c>] (ksoftirqd+0x0/0x2e8) from [<c0064024>] (kthread+0x54/0x80)
Dec  7 17:00:17 kernel: [<c0063fd0>] (kthread+0x0/0x80) from [<c0052270>] (do_exit+0x0/0x670)
Dec  7 17:00:17 kernel:  r5:00000000 r4:00000000
Dec  7 17:00:17 kernel: ---[ end trace 21a3081378dd7793 ]---
Dec  7 17:00:18 squeezeplay: debug_pagefaults:192 Pagefaults, Major:0 Minor:1
[09-12-07 17:01:59.3345] Slim::Utils::Misc::msg (1164) Warning: [17:01:59.3322] Use of uninitialized value in anonymous hash ({}) at /usr/squeezecenter/Slim/Utils/Strings.pm line 414.
Dec  7 17:01:59 slimserver.pl: [09-12-07 17:01:59.3441] Slim::Utils::Misc::msg (1164) Warning: [17:01:59.3322] Use of uninitialized value in anonymous hash ({}) at /usr/squeezecenter/Slim/Utils/Strings.pm line 414.
==================================================================
Comment 9 Michael Herger 2010-01-08 02:09:02 UTC
bug 15429 might be another report of the same issue?
Comment 10 SVN Bot 2010-01-14 00:23:29 UTC
 == Auto-comment from SVN commit #8337 to the jive repo by ayoung ==
 == https://svn.slimdevices.com/jive?view=revision&revision=8337 ==

Fixed bug 15152: Continuous playing of same playlist eventually results in reboot 
Format-specific decoder object was being leaked in the track-to-track continuous-play case. Free the object at end of each track (decode_song_ended_handler()).
Comment 11 Alan Young 2010-01-14 00:28:10 UTC
Originally this was filled as a Touch/TinySC bug. It would simply have been easier to reproduce in that case.
Comment 12 Chris Owens 2010-04-08 17:26:20 UTC
This bug has been marked fixed in a released version of Squeezebox Server or the accompanying firmware or mysqueezebox.com release.

If you are still seeing this issue, please let us know!