Bug 7111 - MusicIP Send To > SlimServer broken
: MusicIP Send To > SlimServer broken
Status: CLOSED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: MusicIP
: 7.0
: PC Windows XP
: P2 normal (vote)
: 7.x
Assigned To: Michael Herger
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2008-02-12 18:44 UTC by windowshade
Modified: 2009-09-08 09:31 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments
requested log (37.05 KB, text/plain)
2008-02-13 07:49 UTC, windowshade
Details

Note You need to log in before you can comment on or make changes to this bug.
Description windowshade 2008-02-12 18:44:08 UTC
After I updated SC 7.0 to the 2008-02-11 nightly, MusicIP's "Send To > SlimServer" function failed to work. The client playlist is cleared, but the MIP playlist does not appear. The player is left with a blank playlist. (This is NOT the old "Error sending playlist to player" bug. MIP doesn't give an error message here.)

I've rolled back to the 2007-11-19 build and the problem went away. Nothing has changed with MusicIP in the interval.

SqueezeCenter Version: 7.0 - 2008-02-11 nightly (173??, sorry) - Windows XP - EN - cp1252
MusicIP 1.8
Comment 1 KDF 2008-02-12 20:27:54 UTC
works fine here:

[08-02-12 20:22:48.4353] Slim::Plugin::CLI::Plugin::cli_request_write (656) player name
[08-02-12 20:22:48.4359] Slim::Plugin::CLI::Plugin::client_socket_buffer (480) 127.0.0.1:56032
[08-02-12 20:22:48.4373] Slim::Plugin::CLI::Plugin::client_socket_write (424) 127.0.0.1:56032
[08-02-12 20:22:48.4377] Slim::Plugin::CLI::Plugin::client_socket_write (437) 127.0.0.1:56032 - Sending response [player name 7 Mercur...]
[08-02-12 20:22:48.4380] Slim::Plugin::CLI::Plugin::client_socket_write (463) Sent response to 127.0.0.1:56032
[08-02-12 20:22:48.4386] Slim::Plugin::CLI::Plugin::client_socket_read (289) Begin Function
[08-02-12 20:22:48.4389] Slim::Plugin::CLI::Plugin::client_socket_read (323) 127.0.0.1:56032 - Buffered [00:04:20:10:02:5b playlist play %2fmyth%2fmusic%2fTapes%2f24-7%20Spyz%2fHarder%20Than%20You%2f24-7%20Spyz%20-%20(07)%20Jungle%20Boogie.mp3
]
[08-02-12 20:22:48.4391] Slim::Plugin::CLI::Plugin::client_socket_buf_parse (362) 127.0.0.1:56032
[08-02-12 20:22:48.4395] Slim::Plugin::CLI::Plugin::cli_process (501) 00:04:20:10:02:5b playlist play %2fmyth%2fmusic%2fTapes%2f24-7%20Spyz%2fHarder%20Than%20You%2f24-7%20Spyz%20-%20(07)%20Jungle%20Boogie.mp3
[08-02-12 20:22:48.4398] Slim::Plugin::CLI::Plugin::cli_process (518) Parsing command: Found client [00:04:20:10:02:5b]
[08-02-12 20:22:48.4402] Slim::Control::Request::__parse (2319) Request: parse(playlist play /myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3)
[08-02-12 20:22:48.4405] Slim::Control::Request::__parse (2339) ..Trying to match [playlist]
[08-02-12 20:22:48.4407] Slim::Control::Request::__parse (2424) ..[playlist] is HASH
[08-02-12 20:22:48.4410] Slim::Control::Request::__parse (2339) ..Trying to match [play]
[08-02-12 20:22:48.4412] Slim::Control::Request::__parse (2424) ..[play] is HASH
[08-02-12 20:22:48.4415] Slim::Control::Request::__parse (2339) ..Trying to match [/myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3]
[08-02-12 20:22:48.4418] Slim::Control::Request::__parse (2347) ..no match for [/myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3]
[08-02-12 20:22:48.4420] Slim::Control::Request::__parse (2363) ...looking for a key starting with _
[08-02-12 20:22:48.4423] Slim::Control::Request::__parse (2369) ....considering [_item]
[08-02-12 20:22:48.4426] Slim::Control::Request::__parse (2375) ....[_item] starts with _
[08-02-12 20:22:48.4429] Slim::Control::Request::__parse (2382) ....not out of verbs, adding param [_item, /myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3]
[08-02-12 20:22:48.4431] Slim::Control::Request::__parse (2405) ..[_item] is ARRAY -> done
[08-02-12 20:22:48.4435] Slim::Plugin::CLI::Plugin::cli_process (568) Processing request [playlist]
[08-02-12 20:22:48.4438] Slim::Plugin::CLI::Plugin::cli_process (602) Dispatching [playlist]
[08-02-12 20:22:48.4445] Slim::Control::Request::execute (1715) Enter
[08-02-12 20:22:48.4452] Slim::Control::Request::dump (2208) Request: Command [00:04:20:10:02:5b->playlist play] from CLI  (Dispatchable)
[08-02-12 20:22:48.4458] Slim::Control::Request::dump (2212)    Param: [_item] = [/myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3]
[08-02-12 20:22:48.4462] Slim::Control::Commands::playlistXitemCommand (1027) Begin Function
[08-02-12 20:22:48.4466] Slim::Control::Commands::playlistXitemCommand (1045) cmd : play
[08-02-12 20:22:48.4469] Slim::Control::Commands::playlistXitemCommand (1046) item: /myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3
[08-02-12 20:22:48.4471] Slim::Control::Commands::playlistXitemCommand (1071) url: /myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3
[08-02-12 20:22:48.4474] Slim::Control::Commands::playlistXitemCommand (1113) path: /myth/music/Tapes/24-7 Spyz/Harder Than You/24-7 Spyz - (07) Jungle Boogie.mp3
[08-02-12 20:22:48.4482] Slim::Control::Request::notifyFromArray (775) (menustatus ARRAY(0xeb795b0) add 00:04:20:10:02:5b)
[08-02-12 20:22:48.4487] Slim::Control::Request::__parse (2319) Request: parse(menustatus ARRAY(0xeb795b0) add 00:04:20:10:02:5b)



Try setting plugin.cli to DEBUG and make sure the command is getting through. control.command to DEBUG will also let you see SC reacting to the commands.

MusicIP 1.8, SqueezeCenter Version: 7.0.1 - 17491 - Debian - CA - utf8
Comment 2 Blackketter Dean 2008-02-13 06:14:03 UTC
Windowshade: Can you capture the debug log?
Comment 3 windowshade 2008-02-13 07:49:15 UTC
Created attachment 2861 [details]
requested log

Thanks for your help. I've included the whole log to ensure I captured the relevant info. 

Of interest: "
[08-02-13 08:42:28.7515] Slim::Control::Request::__parse (2339) ..Trying to match [F:\Audio Archive\Andrew Bird\[2005] The Mysterious Production of Eggs\02 - Sovay.flac]
[08-02-13 08:42:28.7520] Slim::Control::Request::__parse (2347) ..no match for [F:\Audio Archive\Andrew Bird\[2005] The Mysterious Production of Eggs\02 - Sovay.flac]" 

The file is present at that location and other apps access it with no problems.
Comment 4 Blackketter Dean 2008-02-13 08:22:44 UTC
KDF: can you look at this?
Comment 5 KDF 2008-02-13 08:25:03 UTC
How is SC getting access to that track?  It is indexed as f:\Audio Archive ? Is it a mapped network drive? 
Comment 6 windowshade 2008-02-13 12:12:38 UTC
That's in internal hard drive installed in the desktop that acts as my server: not a network drive. The address/location is correct. SC has no trouble accessing the track(s)--say from the web interface or from the remote/SB3. It just gets lost when passed to the server as a playlist from MIP.
Comment 7 KDF 2008-02-13 12:30:30 UTC
Are you able to play this song with SC directly (either via the remote or web ui)?
F:\Audio Archive\Andrew Bird\[2005] The Mysterious Production of Eggs\02 - Sovay.flac

try also player.source debugging as it may report something more about WHY the 'playlist play' command is returning "can't open"

Comment 8 KDF 2008-02-13 12:36:04 UTC
nevermind those tests.  I have a case of it here.  Seems it's windows only.
Comment 9 KDF 2008-02-13 14:11:13 UTC
I've narrowed it down to a problem with Slim::Utils::Misc::fileFilter, created in change 15460.  Using the older file_Filer in Scanner.pm, from 15459 works.  
Comment 10 KDF 2008-02-13 14:49:57 UTC
Slim::Utils::Misc::fileFilter is converting playlist play file/urls into:
\\N:\\MUSIC\\Tragically Hip\\Hipeponymous\\The Tragically Hip - (21) Courage.mp3

Thus, they fail at line 869 with $attributes = -1 (file doesn't exist)

Comment 11 KDF 2008-02-13 15:48:00 UTC
fixed in change 17520.

This problem also affects CLI when used to play a specific file.  It should be considered for 7.0 so I'm leaving open in case we want to back-merge it.
Comment 12 Blackketter Dean 2008-02-13 16:23:02 UTC
Michael/KDF: What's the impact of this bug not going into 7.0?  Anything beyond the MusicIP issue?

Comment 13 KDF 2008-02-13 16:44:46 UTC
anything that tries to play a file by path will fail. Web UI, player UI should all be using fileUrls so internal use shouldn't have a problem. Jive, I assume, is using id values.



Comment 14 windowshade 2008-02-13 18:35:19 UTC
Thanks KDF. I've tested your revision using ActivePerl and it appears to fix the problem. 

I thank you for your attention and for resolving my complaint; Canada thanks you for playing The Hip. ;)
Comment 15 Michael Herger 2008-02-13 22:19:34 UTC
Dean - merged back to SC7.0 in change 17532. The fix looks simple with little risk to break anything.

Thanks kdf!
Comment 16 James Richardson 2008-05-09 15:36:31 UTC
Verified fixed in 7.0.1 - 19597
Comment 17 James Richardson 2008-05-15 12:27:57 UTC
This bug has recently been fixed in the latest release of SqueezeCenter 7.0.1

Please try that version, if you still see the error, then reopen this bug.

To download this version, please navigate to: http://www.slimdevices.com/su_downloads.html
Comment 18 Chris Owens 2009-07-31 10:17:06 UTC
Reduce number of active targets for SC