Bug 6093 - MusicIP playlist cannot be played
: MusicIP playlist cannot be played
Status: RESOLVED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: MusicIP
: 7.0
: PC Windows XP
: P2 normal (vote)
: ---
Assigned To: Unassigned bug - please assign me!
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2007-11-08 07:44 UTC by Doug Williams
Modified: 2007-11-15 09:35 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Doug Williams 2007-11-08 07:44:00 UTC
Nov. 8 build

Cannot send the full playlist to play or add.  It tries to refresh the right side playlist but it goes back to empty.

Adding single tracks from the MIP playlist DOES work, however.
Comment 1 Doug Williams 2007-11-08 07:47:52 UTC
May just be a first play problem.  After adding a few single songs to the right side playlist.  I could add full musicIP playlists.

The album musicip playlist was the first thing that I did after installing the 11/8 build.
Comment 2 KDF 2007-11-08 09:41:57 UTC
seems to work here.  svn 14513.  Try checking logs, set control.command and plugin.musicmagic to DEBUG.
Comment 3 Doug Williams 2007-11-08 18:39:54 UTC
Here is the log file entries.  There is a lot more.  It does appear to be working fine now.

[20:32:42.0507] Slim::Control::Request::__parse (2285) Request: parse(status - 1 tags:uB)
[20:32:42.0513] Slim::Control::Request::__parse (2305) ..Trying to match [status]
[20:32:42.0518] Slim::Control::Request::__parse (2390) ..[status] is HASH
[20:32:42.0522] Slim::Control::Request::__parse (2305) ..Trying to match [-]
[20:32:42.0527] Slim::Control::Request::__parse (2313) ..no match for [-]
[20:32:42.0531] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:42.0535] Slim::Control::Request::__parse (2335) ....considering [_index]
[20:32:42.0540] Slim::Control::Request::__parse (2341) ....[_index] starts with _
[20:32:42.0544] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_index, -]
[20:32:42.0549] Slim::Control::Request::__parse (2390) ..[_index] is HASH
[20:32:42.0553] Slim::Control::Request::__parse (2305) ..Trying to match [1]
[20:32:42.0557] Slim::Control::Request::__parse (2313) ..no match for [1]
[20:32:42.0561] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:42.0566] Slim::Control::Request::__parse (2335) ....considering [_quantity]
[20:32:42.0570] Slim::Control::Request::__parse (2341) ....[_quantity] starts with _
[20:32:42.0574] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_quantity, 1]
[20:32:42.0579] Slim::Control::Request::__parse (2371) ..[_quantity] is ARRAY -> done
[20:32:42.0585] Slim::Control::Request::execute (1682) Enter
[20:32:42.0592] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0588] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Dispatchable)
[20:32:42.0598] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0594]    Param: [_index] = [-]
[20:32:42.0603] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0600]    Param: [_quantity] = [1]
[20:32:42.0609] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0605]    Param: [tags] = [uB]
[20:32:42.0742] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0726] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Done)
[20:32:42.0761] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0752]    Param: [_index] = [-]
[20:32:42.0776] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0768]    Param: [_quantity] = [1]
[20:32:42.0789] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0781]    Param: [tags] = [uB]
[20:32:42.0801] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0793]    Result: [player_name] = [Main Level]
[20:32:42.0811] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0805]    Result: [player_connected] = [1]
[20:32:42.0817] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0814]    Result: [power] = [1]
[20:32:42.0823] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0820]    Result: [signalstrength] = [0]
[20:32:42.0829] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0825]    Result: [mode] = [pause]
[20:32:42.0834] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0831]    Result: [time] = [9]
[20:32:42.0840] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0836]    Result: [rate] = [1]
[20:32:42.0847] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0842]    Result: [duration] = [244]
[20:32:42.0854] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0850]    Result: [mixer volume] = [50]
[20:32:42.0859] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0856]    Result: [playlist repeat] = [2]
[20:32:42.0865] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0861]    Result: [playlist shuffle] = [0]
[20:32:42.0871] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0867]    Result: [playlist_cur_index] = [0]
[20:32:42.0876] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0873]    Result: [playlist_timestamp] = [1194540578.32813]
[20:32:42.0882] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0878]    Result: [playlist_tracks] = [60]
[20:32:42.0888] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0884]    Result: [playlist_loop] is loop with 1 elements:
[20:32:42.0894] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0890]    Result:   0. [playlist index] = [0]
[20:32:42.0900] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0896]    Result:   0. [id] = [2983]
[20:32:42.0905] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0902]    Result:   0. [title] = [Fever for the Flava]
[20:32:42.0911] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.0907]    Result:   0. [url] = [file:///D:/Music/Various%20Artists/American%20Wedding/09-Fever%20for%20the%20Flava-Hot%20Action%20Cop.flac]
[20:32:42.0916] Slim::Control::Request::executeDone (1750) 0
[20:32:42.0952] Slim::Control::Request::__parse (2285) Request: parse(status - 1 tags:uB)
[20:32:42.0958] Slim::Control::Request::__parse (2305) ..Trying to match [status]
[20:32:42.0963] Slim::Control::Request::__parse (2390) ..[status] is HASH
[20:32:42.0968] Slim::Control::Request::__parse (2305) ..Trying to match [-]
[20:32:42.0972] Slim::Control::Request::__parse (2313) ..no match for [-]
[20:32:42.0977] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:42.0981] Slim::Control::Request::__parse (2335) ....considering [_index]
[20:32:42.0985] Slim::Control::Request::__parse (2341) ....[_index] starts with _
[20:32:42.0989] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_index, -]
[20:32:42.0994] Slim::Control::Request::__parse (2390) ..[_index] is HASH
[20:32:42.0998] Slim::Control::Request::__parse (2305) ..Trying to match [1]
[20:32:42.1002] Slim::Control::Request::__parse (2313) ..no match for [1]
[20:32:42.1007] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:42.1011] Slim::Control::Request::__parse (2335) ....considering [_quantity]
[20:32:42.1015] Slim::Control::Request::__parse (2341) ....[_quantity] starts with _
[20:32:42.1019] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_quantity, 1]
[20:32:42.1024] Slim::Control::Request::__parse (2371) ..[_quantity] is ARRAY -> done
[20:32:42.1030] Slim::Control::Request::execute (1682) Enter
[20:32:42.1037] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1033] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Dispatchable)
[20:32:42.1043] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1039]    Param: [_index] = [-]
[20:32:42.1049] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1045]    Param: [_quantity] = [1]
[20:32:42.1054] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1051]    Param: [tags] = [uB]
[20:32:42.1160] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1155] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Done)
[20:32:42.1166] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1163]    Param: [_index] = [-]
[20:32:42.1172] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1169]    Param: [_quantity] = [1]
[20:32:42.1178] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1174]    Param: [tags] = [uB]
[20:32:42.1184] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1180]    Result: [player_name] = [Main Level]
[20:32:42.1190] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1186]    Result: [player_connected] = [1]
[20:32:42.1195] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1192]    Result: [power] = [1]
[20:32:42.1201] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1197]    Result: [signalstrength] = [0]
[20:32:42.1207] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1203]    Result: [mode] = [pause]
[20:32:42.1212] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1209]    Result: [time] = [9]
[20:32:42.1218] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1214]    Result: [rate] = [1]
[20:32:42.1223] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1220]    Result: [duration] = [244]
[20:32:42.1229] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1225]    Result: [mixer volume] = [50]
[20:32:42.1235] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1231]    Result: [playlist repeat] = [2]
[20:32:42.1241] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1237]    Result: [playlist shuffle] = [0]
[20:32:42.1248] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1244]    Result: [playlist_cur_index] = [0]
[20:32:42.1253] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1250]    Result: [playlist_timestamp] = [1194540578.32813]
[20:32:42.1259] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1255]    Result: [playlist_tracks] = [60]
[20:32:42.1265] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1261]    Result: [playlist_loop] is loop with 1 elements:
[20:32:42.1273] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1267]    Result:   0. [playlist index] = [0]
[20:32:42.1279] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1275]    Result:   0. [id] = [2983]
[20:32:42.1286] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1281]    Result:   0. [title] = [Fever for the Flava]
[20:32:42.1292] Slim::Utils::Misc::msg (1210) Warning: [20:32:42.1289]    Result:   0. [url] = [file:///D:/Music/Various%20Artists/American%20Wedding/09-Fever%20for%20the%20Flava-Hot%20Action%20Cop.flac]
[20:32:42.1298] Slim::Control::Request::executeDone (1750) 0
[20:32:46.1877] Slim::Control::Request::__parse (2285) Request: parse(status - 1 tags:uB)
[20:32:46.1882] Slim::Control::Request::__parse (2305) ..Trying to match [status]
[20:32:46.1886] Slim::Control::Request::__parse (2390) ..[status] is HASH
[20:32:46.1891] Slim::Control::Request::__parse (2305) ..Trying to match [-]
[20:32:46.1895] Slim::Control::Request::__parse (2313) ..no match for [-]
[20:32:46.1899] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:46.1904] Slim::Control::Request::__parse (2335) ....considering [_index]
[20:32:46.1908] Slim::Control::Request::__parse (2341) ....[_index] starts with _
[20:32:46.1912] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_index, -]
[20:32:46.1917] Slim::Control::Request::__parse (2390) ..[_index] is HASH
[20:32:46.1921] Slim::Control::Request::__parse (2305) ..Trying to match [1]
[20:32:46.1925] Slim::Control::Request::__parse (2313) ..no match for [1]
[20:32:46.1930] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:46.1934] Slim::Control::Request::__parse (2335) ....considering [_quantity]
[20:32:46.1938] Slim::Control::Request::__parse (2341) ....[_quantity] starts with _
[20:32:46.1942] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_quantity, 1]
[20:32:46.1947] Slim::Control::Request::__parse (2371) ..[_quantity] is ARRAY -> done
[20:32:46.1953] Slim::Control::Request::execute (1682) Enter
[20:32:46.1960] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.1956] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Dispatchable)
[20:32:46.1966] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.1962]    Param: [_index] = [-]
[20:32:46.1972] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.1968]    Param: [_quantity] = [1]
[20:32:46.1977] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.1974]    Param: [tags] = [uB]
[20:32:46.2174] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2169] Request: Query [00:04:20:07:60:63->status] from JSONRPC  (Done)
[20:32:46.2180] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2177]    Param: [_index] = [-]
[20:32:46.2186] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2183]    Param: [_quantity] = [1]
[20:32:46.2192] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2188]    Param: [tags] = [uB]
[20:32:46.2198] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2194]    Result: [player_name] = [Main Level]
[20:32:46.2204] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2200]    Result: [player_connected] = [1]
[20:32:46.2210] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2206]    Result: [power] = [1]
[20:32:46.2215] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2212]    Result: [signalstrength] = [0]
[20:32:46.2221] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2217]    Result: [mode] = [pause]
[20:32:46.2226] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2223]    Result: [time] = [9]
[20:32:46.2232] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2228]    Result: [rate] = [1]
[20:32:46.2237] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2234]    Result: [duration] = [244]
[20:32:46.2243] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2239]    Result: [mixer volume] = [50]
[20:32:46.2249] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2245]    Result: [playlist repeat] = [2]
[20:32:46.2254] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2251]    Result: [playlist shuffle] = [0]
[20:32:46.2260] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2257]    Result: [playlist_cur_index] = [0]
[20:32:46.2266] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2262]    Result: [playlist_timestamp] = [1194540578.32813]
[20:32:46.2271] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2268]    Result: [playlist_tracks] = [60]
[20:32:46.2277] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2273]    Result: [playlist_loop] is loop with 1 elements:
[20:32:46.2283] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2279]    Result:   0. [playlist index] = [0]
[20:32:46.2289] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2286]    Result:   0. [id] = [2983]
[20:32:46.2295] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2291]    Result:   0. [title] = [Fever for the Flava]
[20:32:46.2300] Slim::Utils::Misc::msg (1210) Warning: [20:32:46.2297]    Result:   0. [url] = [file:///D:/Music/Various%20Artists/American%20Wedding/09-Fever%20for%20the%20Flava-Hot%20Action%20Cop.flac]
[20:32:46.2305] Slim::Control::Request::executeDone (1750) 0
[20:32:47.0784] Slim::Control::Request::__parse (2285) Request: parse(status - 1 tags:uB)
[20:32:47.0791] Slim::Control::Request::__parse (2305) ..Trying to match [status]
[20:32:47.0796] Slim::Control::Request::__parse (2390) ..[status] is HASH
[20:32:47.0800] Slim::Control::Request::__parse (2305) ..Trying to match [-]
[20:32:47.0805] Slim::Control::Request::__parse (2313) ..no match for [-]
[20:32:47.0809] Slim::Control::Request::__parse (2329) ...looking for a key starting with _
[20:32:47.0814] Slim::Control::Request::__parse (2335) ....considering [_index]
[20:32:47.0818] Slim::Control::Request::__parse (2341) ....[_index] starts with _
[20:32:47.0822] Slim::Control::Request::__parse (2348) ....not out of verbs, adding param [_index, -]
Comment 4 Michael Herger 2007-11-13 08:10:10 UTC
Again - is this still an issue? Or is it related to the same delay you saw in bug 6092?
Comment 5 Doug Williams 2007-11-13 09:27:24 UTC
Can't dup. this one anymore either.  Might have been related to the previous one - they were occuring with the same builds, etc.  There were a bunch of warnings in the logs - another reason why I left it open just in case someone needed to look at them to see if there really was some sort of issue.  Again the logs were created when it was working...
Otherwise OK to close.  Thanks.
Comment 6 Michael Herger 2007-11-15 09:35:36 UTC
thanks - feel free to re-open