PDA

View Full Version : SBC Search hangs SC, SBR, SB, Transporter



networker
2008-07-23, 17:05
Hi Developer and Slim Device User,

I use a Receiver+Controller (Duet), SB3 and a Transporter with latest SC 7.1 (The Problem occurs also on previous versions) on a Win XP SP2.

With SB3 and Transporter there where no problems to search on Title in a round 80.0000 Title Music Library (MP3/AIF).

When I first include the SBC to the network of other "SB's" it was normaly included into the system and raw functionality was ok.

The problem starts when I start a title search with SBC eg. "who" which returned many entries. (For SC, SB3 and Transporter the same search does not make any problem)

I also tried different new versions of SC and updating SBC, but same false results. With this or other searches with huge search results it hangs all devices including SC on the XP.

Is there any limitation of library size for SBC when making a search?


Thanks for your help !




Protocols of SBC messages after searching (which hangs SBC, SBR, Tranporter and SC):

--------------------------------------------------------------
Jul 24 00:46:43 SqueezeboxController user.info jive: (NetworkThread.lua:249) -
NOTIFY: playerTrackChange(Player {Squeezebox-Duet}, 81493)
Jul 24 00:46:43 SqueezeboxController user.info jive: (NowPlayingApplet.lua:173)
- Notification received that track has changed
Jul 24 00:46:43 SqueezeboxController user.info jive: (Wireless.lua:564) - iwcon
fig power off
Jul 24 00:46:44 SqueezeboxController user.info jive: (Wireless.lua:561) - iwcon
fig power on
Jul 24 00:47:02 SqueezeboxController user.info jive: (SlimBrowserApplet.lua:882
) - _menuSink()
Jul 24 00:47:28 SqueezeboxController user.info jive: (Wireless.lua:564) - iwcon
fig power off
Jul 24 00:47:46 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
584) - setPowerState=dimmed acpower=false
Jul 24 00:47:46 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
997) - Set CPU speed 200000
Jul 24 00:47:48 SqueezeboxController user.info jive: (Wireless.lua:561) - iwcon
fig power on
Jul 24 00:48:24 SqueezeboxController user.err jive: (NetworkThread.lua:139) - n
etwork thread timeout for Task(SocketHttp {syntrak_Chunked}(R))
Jul 24 00:48:24 SqueezeboxController user.err jive: (SocketHttp.lua:781) - Sock
etHttp {syntrak_Chunked}:t_rcvResponse.pump() error:inactivity timeout
Jul 24 00:48:24 SqueezeboxController user.info jive: (Comet.lua:745) - Comet {s
:



.....



Jul 24 00:43:42 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 00:43:44 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
997) - Set CPU speed 200000
etHttp {syntrak_Chunked}:t_rcvResponse.pump() error:inactivity timeout
a:166) - Removing server SlimServer {syntrak}
Jul 24 00:53:49 SqueezeboxController user.info jive: (SqueezeDiscoveryApplet.lu
NOTIFY: serverDelete(SlimServer {syntrak})
NOTIFY: serverDelete(SlimServer {syntrak})
NOTIFY: serverDelete(SlimServer {syntrak})
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
Jul 24 01:13:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
133) - syncing system clock to hw clock: Thu Jul 24 01:13:12 2008
Jul 24 01:13:13 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
136) - system clock now synced to hw clock: Thu Jul 24 01:13:13 2008
Jul 24 01:23:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
133) - syncing system clock to hw clock: Thu Jul 24 01:23:12 2008
Jul 24 01:23:13 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
136) - system clock now synced to hw clock: Thu Jul 24 01:23:13 2008
Jul 24 01:33:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
133) - syncing system clock to hw clock: Thu Jul 24 01:33:12 2008
Jul 24 01:33:13 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
136) - system clock now synced to hw clock: Thu Jul 24 01:33:13 2008
Jul 24 01:43:07 SqueezeboxController local0.info udhcpc[321]: Sending renew...
Jul 24 01:43:07 SqueezeboxController local0.info udhcpc[321]: Lease of 10.12.25
2.15 obtained, lease time 3600
Jul 24 01:43:07 SqueezeboxController user.notice root: udhcpc_action eth0 renew
ip=10.12.252.15
Jul 24 01:43:12 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
133) - syncing system clock to hw clock: Thu Jul 24 01:43:12 2008
Jul 24 01:43:13 SqueezeboxController user.info jive: (SqueezeboxJiveApplet.lua:
136) - system clock now synced to hw clock: Thu Jul 24 01:43:13 2008
-------------------------------------------------------------------------

mherger
2008-07-23, 21:47
> I also tried different new versions of SC and updating SBC, but same
> false results.

What do you mean by false results? Do you get results, but they're
incorrect?

> With this or other searches with huge search results it
> hangs all devices including SC on the XP.

Does the system hang permanently? Does SC crash? Or does it just take an
awful long time to return?

> Protocols of SBC messages after searching (which hangs SBC, SBR,
> Tranporter and SC):

server.log might be even more important.

Michael

networker
2008-07-24, 14:38
Hi Michael,

thanks for your quick response, here the further information:

>What do you mean by false results? Do you get results, but they're
>incorrect?

No, I mean SBC shows the same behavior with the title search in huge lists.
- no connection to SC
- wlan indicator switches to blue
- after reboot of SBC and restart of SC (or waiting for SC to come back after a awful long time) it works again with the same behavior.


>Does the system hang permanently? Does SC crash? Or does it just take an
>awful long time to return?

The system always hang after a search with sbc where a lot of hit are expected. For example, if I start a title search with the infrared remote on Transporter for "WHO" I get 288 hits listed in about 5 sec. If I try the same on the SBC with Transporter as selected player, the following behavior happens:

1. SBC displays "Suchvorgang gestartet" (searching)
2. Transporter interrupt with playing the actual title
3. SBC displays "Verbindung mit Server" (connection to Server)
4. SBC displays "Verbindungsproblem" (connection problem) with th follwing option
- "Erneut versuchen" (try again)
- "Musikquelle wählen" (choose music source)
5. Actual title is displayed as screensaver, but Transport does not play it
6. After 7 minutes Transporter interruptly plays music from the next playlist song for some seconds, stops playing, start playing again for seconds and then timed out for 13 min. After round 20 min. Transporter continue with music playing.
7. SBC shows the old actual title from the time where the search was started
8. In the main menue SBS shows a blue w-lan indicator
9. I switch off and restart SBC
10. SBC is "Connecting to Transporter"
11. Transporter interrupt and stops playing the actual title
12. after 5 min. SBC still try to connect to Transporter. Transporter is still not playing music
13. After round 15 min Transportr start playing again for several minutes, but interrupt several time and the continue without interruption
14. SBC after 45 min. still try connecting to Transporter
14. At this point I will pass you the server.log

Some more Information:
- When I use Transporter infrared remote or SC Web Interface there is no such problem at all
- Music Library collected with iTunes 7.7 (about 85.000 title)
- Music source on ReadyNAS NV+, X-Raid, 1GB RAM (mapped as M:/Musik)
- SC imported music library from iTunes, rescan intervall "0"
- SC runs on XPSP2 Pentium 4 3.2 Ghz, 3GB RAM
-Versions:
{
SqueezeCenter Informationen

SqueezeCenter-Version: 7.1 - 21967 @ Tue Jul 22 01:03:27 PDT 2008 - Windows XP - DE - cp1252
IP-Adresse des Servers: 10.12.252.12
Perl-Version: 5.8.8 MSWin32-x86-multi-thread
MySQL-Version: 5.0.22-community-nt
Plattformarchitektur: 586
Hostname: syntrak
Server-Portnummer: 9000
Anzahl erkannte Player: 1


Player-Informationen

Name: Transporter
Modell: transporter
Firmware: 50
Die IP-Adresse für diesen Player ist: 10.12.252.25:46809
Die MAC-Adresse für diesen Player ist: 00:04:20:10:0b:63
Spannung: 233


SqueezBox Controller:

Jive Version 7.1 r2704 (r2722 tested but same behavior)
}



> server.log might be even more important.

Here is the cutout of server.log from 24.07.2008
(From my view there is no logging of the problems, there is no entry from the test timeframe listed above from 22:00 to 23:30)

Server.log:
[08-07-24 02:34:37.4533] Slim::Networking::Async::DNS::init (102) Warning: No DNS servers responded, falling back to OpenDNS.
[08-07-24 02:39:14.0793] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 02:40:07.1191] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 02:42:26.6746] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 02:44:12.3567] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 02:44:21.7555] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 02:48:45.1578] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 03:00:21.0513] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 03:02:14.7992] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 03:02:37.8602] Slim::Control::Commands::playlistcontrolCommand (1567) Begin Function
[08-07-24 19:51:57.4644] Slim::Hardware::IR::executeButton (999) Error: Subroutine for irCode: [arrow_right.hold] does not exist!

networker
2008-07-25, 04:44
Please let me know if further information or discription is needed...

Thanks in advance

networker
2008-07-25, 17:42
I replaced the Duet SBC and SBR

I have done no changes to the SC system, as before on SC I run SqueezeCenter-Version: 7.1 - 21967 @ Tue Jul 22 01:03:27 PDT 2008 - Windows XP - DE - cp1252

The reason to change the Duet was the ping responsetime of the SBC which was about ~20ms to ~300ms where the SBC was in the same place (about 1 meter) near to the receiver. The SBR has always ping times about 3ms to 5ms.

After replacing the units the ping response of the SBC was about 5ms to 8ms. So there must be something wrong with the contoller unit. In the display it shows already full signalstrength but the pingtimes are always
changing and sometimes droped out.

Now after connecting the new unit for example I can change players within 3 to 10 sec, which I think is quite ok, before it tooks plenty of minutes.

But, after I thought everything is nice I start again a search for "WHO" where the connection breaks to the SC. I restart both, the SC and the SBC and try to search "supertr" via interpret search, the sbc displays the different albums and after choose "all albums" it hangs the controller again.
after about 20 minutes the sbc was responding again and I tried the same search... with no problem.

Is that belong to the huge music library or processor which is a 3.2 GHZ Pentium with 3GB RAM (the Siemens CELSIUS Workstation runs for about 2 1/2 ongoing without any issue)? After the 20 minutes a second same search there was no disconnecting event and I got the title list of the search and can play it.

I don't know, maybe somebody out there have the right idea or technical hint for what is going wrong here.

Thanks to all who give me some hints of resolving the problem !

trotsky10
2008-07-27, 01:15
I have a similar problem whereby I can crash squeezecentre quite often by doing a search on the squeezebox 3 while music is playing.

How do I set the logging so I can find out why and post it here?

Many thanks