Bugzilla – Bug 9398
Playing podcast from SqueezeNetwork hangs controller
Last modified: 2012-02-27 17:33:20 UTC
This has previously been reported in bug 9130 . However that one was incorrectly (I think) being marked as a duplicate of bug 9233 When I play a podcast the networking icon on the controller turns blue and the connection to the Squeeze Network is lost. The only way to have it working again is to turn the controller off and then on again. Sometimes it seems to work with some podcasts, but the feed at http://www.sr.se/rssfeed/rssfeed.aspx?Poddfeed=3951 always triggers this bug. I am runing release 7.2, but had this problem also with 7.1.
QA to reproduce
QA is unable to reproduce this error on either SN or SC. Controller 7.2r2873 Peter, are you able to still reproduce this issue with the listed podcast? Andy, do you see anything special about that link that would cause issues?
Yes, I can still reproduce it. However, I have found that only certain shows in the podcast behave like this. The most recent shows work, but all shows from 2008-08-13 or earlier reproduces this issue.
I just found out that it works if I listen through Sqeeze Center, but not if I'm using Squeeze Network.
I am still unable to reproduce. Playing the POD casts from 2008-07-12 through 2008-09-20 via SqueezeNetwrok, I am unable to get my controller to lockup. I allowed each cast to play for 15 minutes, paused, stopped, re-started. All worked. Can you please capture a log from the Controller to an SD card for us? Get an SD card, formatted as either FAT or FAT32 Create a folder on the SD card titled LOG Turn off the Controller, remove the battery cover and battery Place the SD card in the slot in the back of your controller Replace the battery and cover On the controller, go to Settings > Advanced > Logging Set the following logs to DEBUG net.comet player.browse slimserver NOTE: If you do not see a LOGGING option, then the SD card may not be formatted properly, or the LOG file is missing. Attempt to reproduce the error. After you have re-created the error, remove the SD card from your controller, then copy the contents of the LOG folder to this bug. Please do not archive the files, simply attach the TXT files generated.
Certain shows, only on SN...my hunch is that this is a UTF-8 problem.
I will try to generate the log as soon as I've managed to get hold of a card reader. My camera has only PTP support and not UMS so I haven't managed to create the LOG folder yet. Is it a way to create this folder from within the device using SSH?
Created attachment 4062 [details] Controller log file Here's the requested log file. I left all other units at their default log level. Hope this helps.
Ben: does the log help narrow down the issue at all?
I have realized that not only the connection to SN is lost. Also the volume adjustment stops working. I get the visual feedback on the controller, but nothing happens to the sound pressure. I guess that is communicated directly from controller to SB without involving SN. I can generate more logs if needed.
Unfortunately the log is not telling me anything of value, other than it rebooted. No major red flags before the reboot occurred (see below) I just tested this podcast with my local 7.3 SC and with SqueezeNetwork. No controller problems in either case for me... the only thing I see of note in the log around the crash is iwconfig powering on and off, but I'm fairly certain that's intentional behavior as a battery saving measure. Should not have caused a crash either. Since the behavior was limited to when connected to SN and the podcast is Swedish, I'm still under the impression this is a problem with UTF chars being sent to the controller and the controller not handling them correctly. Sep 25 22:08:54 SqueezeboxController user.info jive: (Wireless.lua:564) - iwconfig power off Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/subscribe id=6 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /551031X886df38e50bb2f62c5ba8a8b07704fd1X1222373251Xa97117e2/slim/playerstatus/00:04:20:16:66:ce id=6 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:855) - Comet {SqueezeNetwork}: _response, notifiying callbacks for /slim/playerstatus/00:04:20:16:66:ce Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:858) - callback to: function: 0x201968 Sep 25 22:08:54 SqueezeboxController user.info jive: (NetworkThread.lua:249) - NOTIFY: playerPower(Player {Beosystem 6500}, true) Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:2099) - SlimBrowser.notify_playerPower Sep 25 22:08:54 SqueezeboxController user.info jive: (NetworkThread.lua:249) - NOTIFY: playerPlaylistChange(Player {Beosystem 6500}) Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:2153) - SlimBrowser.notify_playerPlaylistChange Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:452) - Comet {SqueezeNetwork}: request(function: 0x4fd068, reqid:15, 00:04:20:16:66:ce, status,0,200,menu:menu, priority:nil) Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:353) - Sending pending request(s): Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/request id=14 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /551031X886df38e50bb2f62c5ba8a8b07704fd1X1222373251Xa97117e2/slim/menustatus/00:04:20:16:66:ce id=4 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:855) - Comet {SqueezeNetwork}: _response, notifiying callbacks for /slim/menustatus/00:04:20:16:66:ce Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:858) - callback to: function: 0x203ae8 Sep 25 22:08:54 SqueezeboxController user.info jive: (SlimBrowserApplet.lua:994) - _menuSink() Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:1005) - This menu notification was not for this player Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:1006) - Notification for: 00:04:20:12:6c:ac Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:1007) - This player is: 00:04:20:16:66:ce Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/request id=14 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /551031X886df38e50bb2f62c5ba8a8b07704fd1X1222373251Xa97117e2/slim/request id=14 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:855) - Comet {SqueezeNetwork}: _response, notifiying callbacks for /slim/request|14 Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:858) - callback to: function: 0x4fd068 Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:1154) - _statusSink() Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/request id=15 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/request id=15 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /551031X886df38e50bb2f62c5ba8a8b07704fd1X1222373251Xa97117e2/slim/request id=15 OK Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:855) - Comet {SqueezeNetwork}: _response, notifiying callbacks for /slim/request|15 Sep 25 22:08:54 SqueezeboxController user.debug jive: (Comet.lua:858) - callback to: function: 0x4fd068 Sep 25 22:08:54 SqueezeboxController user.debug jive: (SlimBrowserApplet.lua:1154) - _statusSink() Sep 25 22:08:55 SqueezeboxController user.info jive: (Wireless.lua:561) - iwconfig power on Sep 25 22:09:02 SqueezeboxController user.debug jive: (Comet.lua:452) - Comet {SqueezeNetwork}: request(function: 0x7052a0, reqid:16, 00:04:20:16:66:ce, mixer,volume,100, priority:nil) Sep 25 22:09:02 SqueezeboxController user.debug jive: (Comet.lua:353) - Sending pending request(s): Sep 25 22:09:03 SqueezeboxController user.info jive: (Wireless.lua:564) - iwconfig power off Sep 25 22:09:03 SqueezeboxController user.debug jive: (Comet.lua:795) - Comet {SqueezeNetwork}: _response, /slim/request id=16 OK
this isn't going to be fixed by 7.3. retarget for 7.3.1
Is this a problem when playing the podcast on the Controller itself or when controlling a Receiver? I've seen various reports of Controller crashing when playing podcasts on it.
Changing target to next release
with only these exceptions, my bug list is being retargetted for 8.0 for 7.3.2 (very soon): https://bugs-archive.lyrion.org/show_bug.cgi?id=7186 Browse albums/artists/genres/ years should remember previous state for 7.3.3 (later, but before 8.0): https://bugs-archive.lyrion.org/show_bug.cgi?id=7139 Add editable "Network Settings" page https://bugs-archive.lyrion.org/show_bug.cgi?id=7502 Add setup mode to set Static IPs (even if DHCP is present) https://bugs-archive.lyrion.org/show_bug.cgi?id=7829 Jive menu broken if BLOCK_SIZE == list count + 1 and "Pla... https://bugs-archive.lyrion.org/show_bug.cgi?id=8878 Party Mode https://bugs-archive.lyrion.org/show_bug.cgi?id=10064 SqueezeCenter doesn't always auto-generate playerstatus w... (which will fix 8300) https://bugs-archive.lyrion.org/show_bug.cgi?id=6334 add applet for network diagnostics (currently assigned to Tom)
QA to attempt to reproduce with latest firmware.
Peter: is there a posibilty that you can test the latest firmware on your controller? Download SC 7.3.3, connect your devices to that version, which will then update the firmware. Then, reconnect to SN and verify that the podcasts still cause your controller to reboot. You can download SC from here http://downloads.slimdevices.com/nightly/?ver=7.3 I tried it here, but still could not get the controller to reboot. I used a Boom and Receiver as well as the Controller as a player.
I have also been trying to reproduce this with the nightly of 7.3.3 without success. I am going to close this as works for me for now. Please reopen if you see this issue again and add additional comments on how you reproduced the issue.
Closing resolved bugs - if you feel this bug still exists please first re-test with the latest SW/FW version. If you are able to reproduce then feel free to reopen and attach new logs / steps to reproduce.