Bug 2001 - CLI returns %3F plus other data in responses intermittently
: CLI returns %3F plus other data in responses intermittently
Status: RESOLVED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: CLI
: 6.1.1
: PC Windows XP
: P2 normal (vote)
: ---
Assigned To: Fred
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2005-08-20 12:28 UTC by Matt Wald
Modified: 2009-09-08 09:28 UTC (History)
0 users

See Also:
Category: ---


Attachments

Note You need to log in before you can comment on or make changes to this bug.
Description Matt Wald 2005-08-20 12:28:44 UTC
In response to standard commands such as mode, playlist, author, title, etc., 
CLI will return %3F and other data in the response intermittently (see 
below).  I can replicate this by typing the command directly into a telnet 
window, or through a polling program. This behavior occurs in both the Linux 
and Windows versions of Slimserver, with a Squeezebox 1.

Example:  00:04:20:05:5d:ae mode ?
Response: 00%3A04%3A20%3A05%3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae


08-20 15:03:59.1480  Returning array: artist (Steely Dan) () () () () () ()
2005-08-20 15:03:59.1485 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae artist Steely%20Dan
2005-08-20 15:03:59.1493 Sending response
2005-08-20 15:03:59.1497 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.1538 CLI: Excuting command: 00:04:20:05:5d:ae duration ?
2005-08-20 15:03:59.1542  Executing command 00:04:20:05:5d:ae: duration (?) () 
() () () () ()
2005-08-20 15:03:59.1574  Returning array: duration (310.266) () () () () () ()
2005-08-20 15:03:59.1576 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae duration 310.266
2005-08-20 15:03:59.1591 Sending response
2005-08-20 15:03:59.1596 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.1969 CLI: Excuting command: 00:04:20:05:5d:ae time ?
2005-08-20 15:03:59.1973  Executing command 00:04:20:05:5d:ae: time (?) () () 
() () () ()
2005-08-20 15:03:59.1977  Returning array: time (5.93633699417114) () () () () 
() ()
2005-08-20 15:03:59.1979 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae time 5.93633699417114
2005-08-20 15:03:59.1986 Sending response
2005-08-20 15:03:59.1991 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.2018 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
index ?
2005-08-20 15:03:59.2022  Executing command 00:04:20:05:5d:ae: playlist 
(index) (?) () () () () ()
2005-08-20 15:03:59.2030  Returning array: playlist (index) (?
00:04:20:05:5d:ae) () () () () ()
2005-08-20 15:03:59.2033 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist index %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.2040 Sending response
2005-08-20 15:03:59.2044 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.2414 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
tracks ?
2005-08-20 15:03:59.2418  Executing command 00:04:20:05:5d:ae: playlist 
(tracks) (?) () () () () ()
2005-08-20 15:03:59.2424  Returning array: playlist (tracks) (7) () () () () ()
2005-08-20 15:03:59.2427 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist tracks 7
2005-08-20 15:03:59.2435 Sending response
2005-08-20 15:03:59.2439 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.2737 CLI: Excuting command: 00:04:20:05:5d:ae mode ?
2005-08-20 15:03:59.2741  Executing command 00:04:20:05:5d:ae: mode (?) () () 
() () () ()
2005-08-20 15:03:59.2744  Returning array: mode (?00:04:20:05:5d:ae) () () () 
() () ()
2005-08-20 15:03:59.2747 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.2754 Sending response
2005-08-20 15:03:59.2758 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.2978 CLI: Excuting command: 00:04:20:05:5d:ae title ?
2005-08-20 15:03:59.2982  Executing command 00:04:20:05:5d:ae: title (?) () () 
() () () ()
2005-08-20 15:03:59.2990  Returning array: title (Black Cow) () () () () () ()
2005-08-20 15:03:59.2993 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae title Black%20Cow
2005-08-20 15:03:59.3023 Sending response
2005-08-20 15:03:59.3028 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.3263 CLI: Excuting command: 00:04:20:05:5d:ae artist ?
2005-08-20 15:03:59.3268  Executing command 00:04:20:05:5d:ae: artist (?) () 
() () () () ()
2005-08-20 15:03:59.3295  Returning array: artist (Steely Dan) () () () () () 
()
2005-08-20 15:03:59.3301 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae artist Steely%20Dan
2005-08-20 15:03:59.3308 Sending response
2005-08-20 15:03:59.3312 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.3342 CLI: Excuting command: 00:04:20:05:5d:ae duration ?
2005-08-20 15:03:59.3347  Executing command 00:04:20:05:5d:ae: duration (?) () 
() () () () ()
2005-08-20 15:03:59.3354  Returning array: duration (310.266) () () () () () ()
2005-08-20 15:03:59.3357 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae duration 310.266
2005-08-20 15:03:59.3364 Sending response
2005-08-20 15:03:59.3368 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.3769 CLI: Excuting command: 00:04:20:05:5d:ae time ?
2005-08-20 15:03:59.3773  Executing command 00:04:20:05:5d:ae: time (?) () () 
() () () ()
2005-08-20 15:03:59.3777  Returning array: time (6.11638212203979) () () () () 
() ()
2005-08-20 15:03:59.3780 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae time 6.11638212203979
2005-08-20 15:03:59.3786 Sending response
2005-08-20 15:03:59.3790 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.3824 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
index ?
2005-08-20 15:03:59.3828  Executing command 00:04:20:05:5d:ae: playlist 
(index) (?) () () () () ()
2005-08-20 15:03:59.3836  Returning array: playlist (index) (?
00:04:20:05:5d:ae) () () () () ()
2005-08-20 15:03:59.3839 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist index %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.3846 Sending response
2005-08-20 15:03:59.3850 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.4225 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
tracks ?
2005-08-20 15:03:59.4229  Executing command 00:04:20:05:5d:ae: playlist 
(tracks) (?) () () () () ()
2005-08-20 15:03:59.4235  Returning array: playlist (tracks) (7) () () () () ()
2005-08-20 15:03:59.4237 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist tracks 7
2005-08-20 15:03:59.4244 Sending response
2005-08-20 15:03:59.4248 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.5644 CLI: Excuting command: 00:04:20:05:5d:ae mode ?
2005-08-20 15:03:59.5648  Executing command 00:04:20:05:5d:ae: mode (?) () () 
() () () ()
2005-08-20 15:03:59.5652  Returning array: mode (?00:04:20:05:5d:ae) () () () 
() () ()
2005-08-20 15:03:59.5655 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.5662 Sending response
2005-08-20 15:03:59.5666 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.5887 CLI: Excuting command: 00:04:20:05:5d:ae title ?
2005-08-20 15:03:59.5892  Executing command 00:04:20:05:5d:ae: title (?) () () 
() () () ()
2005-08-20 15:03:59.5901  Returning array: title (Black Cow) () () () () () ()
2005-08-20 15:03:59.5904 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae title Black%20Cow
2005-08-20 15:03:59.5916 Sending response
2005-08-20 15:03:59.5920 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.6163 CLI: Excuting command: 00:04:20:05:5d:ae artist ?
2005-08-20 15:03:59.6167  Executing command 00:04:20:05:5d:ae: artist (?) () 
() () () () ()
2005-08-20 15:03:59.6195  Returning array: artist (Steely Dan) () () () () () 
()
2005-08-20 15:03:59.6201 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae artist Steely%20Dan
2005-08-20 15:03:59.6208 Sending response
2005-08-20 15:03:59.6212 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.6453 CLI: Excuting command: 00:04:20:05:5d:ae duration ?
2005-08-20 15:03:59.6457  Executing command 00:04:20:05:5d:ae: duration (?) () 
() () () () ()
2005-08-20 15:03:59.6465  Returning array: duration (310.266) () () () () () ()
2005-08-20 15:03:59.6468 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae duration 310.266
2005-08-20 15:03:59.6474 Sending response
2005-08-20 15:03:59.6478 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.6704 CLI: Excuting command: 00:04:20:05:5d:ae time ?
2005-08-20 15:03:59.6708  Executing command 00:04:20:05:5d:ae: time (?) () () 
() () () ()
2005-08-20 15:03:59.6712  Returning array: time (6.40986394882202) () () () () 
() ()
2005-08-20 15:03:59.6714 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae time 6.40986394882202
2005-08-20 15:03:59.6722 Sending response
2005-08-20 15:03:59.6726 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.6763 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
index ?
2005-08-20 15:03:59.6768  Executing command 00:04:20:05:5d:ae: playlist 
(index) (?) () () () () ()
2005-08-20 15:03:59.6777  Returning array: playlist (index) (?
00:04:20:05:5d:ae) () () () () ()
2005-08-20 15:03:59.6780 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist index %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.6787 Sending response
2005-08-20 15:03:59.6791 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.7158 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
tracks ?
2005-08-20 15:03:59.7163  Executing command 00:04:20:05:5d:ae: playlist 
(tracks) (?) () () () () ()
2005-08-20 15:03:59.7168  Returning array: playlist (tracks) (7) () () () () ()
2005-08-20 15:03:59.7171 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist tracks 7
2005-08-20 15:03:59.7178 Sending response
2005-08-20 15:03:59.7182 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.7409 CLI: Excuting command: 00:04:20:05:5d:ae mode ?
2005-08-20 15:03:59.7413  Executing command 00:04:20:05:5d:ae: mode (?) () () 
() () () ()
2005-08-20 15:03:59.7417  Returning array: mode (?00:04:20:05:5d:ae) () () () 
() () ()
2005-08-20 15:03:59.7420 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.7426 Sending response
2005-08-20 15:03:59.7430 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.7645 CLI: Excuting command: 00:04:20:05:5d:ae title ?
2005-08-20 15:03:59.7649  Executing command 00:04:20:05:5d:ae: title (?) () () 
() () () ()
2005-08-20 15:03:59.7656  Returning array: title (Black Cow) () () () () () ()
2005-08-20 15:03:59.7659 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae title Black%20Cow
2005-08-20 15:03:59.7666 Sending response
2005-08-20 15:03:59.7670 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.7910 CLI: Excuting command: 00:04:20:05:5d:ae artist ?
2005-08-20 15:03:59.7914  Executing command 00:04:20:05:5d:ae: artist (?) () 
() () () () ()
2005-08-20 15:03:59.7942  Returning array: artist (Steely Dan) () () () () () 
()
2005-08-20 15:03:59.7949 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae artist Steely%20Dan
2005-08-20 15:03:59.7959 Sending response
2005-08-20 15:03:59.7964 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.7972 CLI: Excuting command: 00:04:20:05:5d:ae duration ?
2005-08-20 15:03:59.7976  Executing command 00:04:20:05:5d:ae: duration (?) () 
() () () () ()
2005-08-20 15:03:59.7983  Returning array: duration (310.266) () () () () () ()
2005-08-20 15:03:59.7986 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae duration 310.266
2005-08-20 15:03:59.7993 Sending response
2005-08-20 15:03:59.7996 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.8221 CLI: Excuting command: 00:04:20:05:5d:ae time ?
2005-08-20 15:03:59.8225  Executing command 00:04:20:05:5d:ae: time (?) () () 
() () () ()
2005-08-20 15:03:59.8229  Returning array: time (6.56157302856445) () () () () 
() ()
2005-08-20 15:03:59.8231 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae time 6.56157302856445
2005-08-20 15:03:59.8238 Sending response
2005-08-20 15:03:59.8242 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.8635 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
index ?
2005-08-20 15:03:59.8639  Executing command 00:04:20:05:5d:ae: playlist 
(index) (?) () () () () ()
2005-08-20 15:03:59.8648  Returning array: playlist (index) (?
00:04:20:05:5d:ae) () () () () ()
2005-08-20 15:03:59.8652 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist index %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.8660 Sending response
2005-08-20 15:03:59.8665 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.8682 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
tracks ?
2005-08-20 15:03:59.8686  Executing command 00:04:20:05:5d:ae: playlist 
(tracks) (?) () () () () ()
2005-08-20 15:03:59.8692  Returning array: playlist (tracks) (7) () () () () ()
2005-08-20 15:03:59.8694 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist tracks 7
2005-08-20 15:03:59.8702 Sending response
2005-08-20 15:03:59.8706 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.9235 CLI: Excuting command: 00:04:20:05:5d:ae mode ?
2005-08-20 15:03:59.9239  Executing command 00:04:20:05:5d:ae: mode (?) () () 
() () () ()
2005-08-20 15:03:59.9243  Returning array: mode (?00:04:20:05:5d:ae) () () () 
() () ()
2005-08-20 15:03:59.9245 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:03:59.9252 Sending response
2005-08-20 15:03:59.9256 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.9475 CLI: Excuting command: 00:04:20:05:5d:ae title ?
2005-08-20 15:03:59.9479  Executing command 00:04:20:05:5d:ae: title (?) () () 
() () () ()
2005-08-20 15:03:59.9488  Returning array: title (Black Cow) () () () () () ()
2005-08-20 15:03:59.9491 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae title Black%20Cow
2005-08-20 15:03:59.9498 Sending response
2005-08-20 15:03:59.9502 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.9739 CLI: Excuting command: 00:04:20:05:5d:ae artist ?
2005-08-20 15:03:59.9743  Executing command 00:04:20:05:5d:ae: artist (?) () 
() () () () ()
2005-08-20 15:03:59.9771  Returning array: artist (Steely Dan) () () () () () 
()
2005-08-20 15:03:59.9777 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae artist Steely%20Dan
2005-08-20 15:03:59.9784 Sending response
2005-08-20 15:03:59.9788 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.0025 CLI: Excuting command: 00:04:20:05:5d:ae duration ?
2005-08-20 15:03:59.0029  Executing command 00:04:20:05:5d:ae: duration (?) () 
() () () () ()
2005-08-20 15:03:59.0036  Returning array: duration (310.266) () () () () () ()
2005-08-20 15:03:59.0039 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae duration 310.266
2005-08-20 15:03:59.0046 Sending response
2005-08-20 15:03:59.0050 No more messages to send to 192.168.1.199
2005-08-20 15:03:59.0083 CLI: Excuting command: 00:04:20:05:5d:ae time ?
2005-08-20 15:03:59.0088  Executing command 00:04:20:05:5d:ae: time (?) () () 
() () () ()
2005-08-20 15:03:59.0091  Returning array: time (6.74780893325806) () () () () 
() ()
2005-08-20 15:03:59.0094 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae time 6.74780893325806
2005-08-20 15:03:59.0101 Sending response
2005-08-20 15:03:59.0105 No more messages to send to 192.168.1.199
2005-08-20 15:04:00.0439 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
index ?
2005-08-20 15:04:00.0444  Executing command 00:04:20:05:5d:ae: playlist 
(index) (?) () () () () ()
2005-08-20 15:04:00.0452  Returning array: playlist (index) (?
00:04:20:05:5d:ae) () () () () ()
2005-08-20 15:04:00.0455 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist index %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:04:00.0462 Sending response
2005-08-20 15:04:00.0466 No more messages to send to 192.168.1.199
2005-08-20 15:04:00.0528 CLI: Excuting command: 00:04:20:05:5d:ae playlist 
tracks ?
2005-08-20 15:04:00.0532  Executing command 00:04:20:05:5d:ae: playlist 
(tracks) (?) () () () () ()
2005-08-20 15:04:00.0538  Returning array: playlist (tracks) (7) () () () () ()
2005-08-20 15:04:00.0541 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae playlist tracks 7
2005-08-20 15:04:00.0551 Sending response
2005-08-20 15:04:00.0555 No more messages to send to 192.168.1.199
2005-08-20 15:04:00.2292 CLI: Excuting command: 00:04:20:05:5d:ae mode ?
2005-08-20 15:04:00.2296  Executing command 00:04:20:05:5d:ae: mode (?) () () 
() () () ()
2005-08-20 15:04:00.2301  Returning array: mode (?00:04:20:05:5d:ae) () () () 
() () ()
2005-08-20 15:04:00.2305 Command line interface response: 00%3A04%3A20%3A05%
3A5d%3Aae mode %3F00%3A04%3A20%3A05%3A5d%3Aae
2005-08-20 15:04:00.2312 Sending response
2005-08-20 15:04:00.2315 No more messages to send to 192.168.1.199
Comment 1 KDF 2005-08-20 13:45:31 UTC
Fred, is this the problem you've been discussing on the forum?
Comment 2 Fred 2005-08-20 14:27:17 UTC
Yes. Will try to nail it now
Comment 3 Fred 2005-08-20 15:21:38 UTC
KDF, Dan, help!!!

This is crazy. 

>CLI: Excuting command: 00:04:20:05:5d:ae mode ?
>Command.pm: Executing command 00:04:20:05:5d:ae: mode (?)

So the CLI parsed command OK and gave good params to Command::execute().

In command.pm, we have:

		} elsif ($p0 eq "mode") {
			if (!defined($p1) || $p1 eq "?") {
				$p1 = Slim::Player::Source::playmode($client);
			} else {
 				[...]
			}
		} elsif ...

and then the callcallback stuff which there isn't since the CLI do not use this execute parameter.
Then there is the executeCallback which calls the CLI (does not write the params) and modifyPlaylist in 
Player.pm which does not touch the params either (and immediately returns for the mode command).

And then we print the returnarray, and that gives:

>Command.pm:  Returning array: mode (?00:04:20:05:5d:ae)
which the CLI rightly returns as:
>CLI: 00... mode %3F00%3A04%3A20%3A05%3A5d%3Aae

I cannot figure how $p1 is assigned "?00:04:20:05:5d:ae". Other people see ?mode or ?playlist, so there 
is something very funky going on. And I can't reproduce that on Mac OS X, no matter how hard i try...

Can one of you try it on Windows or Linux, and maybe try a couple of debug lines to see where things 
go sour? Or maybe you have a brilliant idea...
Comment 4 KDF 2005-08-20 18:11:02 UTC
no luck getting much wrong stuff back here.  tried 6.1.x and 6.2 on linux.  I
can get %3f by using duration ? when there is no playlist.  I get the following
backtrace:

2005-08-20 18:09:07.2526  Executing command 00:04:20:05:aa:bb: duration (?) ()
() () () () ()
2005-08-20 18:09:07.2529 Null track request!
2005-08-20 18:09:07.2532 Backtrace:

   frame 0: Slim::DataStores::DBI::DBIStore::objectForUrl
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/Command.pm line 706)
   frame 1: Slim::Control::Command::execute
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/Stdio.pm line 250)
   frame 2: Slim::Control::Stdio::executeCmd
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/CLI.pm line 271)
   frame 3: Slim::Control::CLI::cli_execute
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/CLI.pm line 210)
   frame 4: Slim::Control::CLI::client_socket_read
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Networking/Select.pm line 115)
   frame 5: Slim::Networking::Select::select (./slimserver.pl line 636)
   frame 6: main::idle (./slimserver.pl line 579)
   frame 7: main::main (./slimserver.pl line 1182)

Use of uninitialized value in concatenation (.) or string at
/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/Command.pm line 721.
2005-08-20 18:09:07.2533 Couldn't fetch object for URL: [] - skipping track
2005-08-20 18:09:07.2535 Backtrace:

   frame 0: Slim::Control::Command::execute
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/Stdio.pm line 250)
   frame 1: Slim::Control::Stdio::executeCmd
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/CLI.pm line 271)
   frame 2: Slim::Control::CLI::cli_execute
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Control/CLI.pm line 210)
   frame 3: Slim::Control::CLI::client_socket_read
(/mnt/mandrake/usr/local/slimserver/6.1.x/Slim/Networking/Select.pm line 115)
   frame 4: Slim::Networking::Select::select (./slimserver.pl line 636)
   frame 5: main::idle (./slimserver.pl line 579)
   frame 6: main::main (./slimserver.pl line 1182)

2005-08-20 18:09:07.2537  Returning array: duration (?) () () () () () ()

Comment 5 Fred 2005-08-21 04:55:51 UTC
Thanks KDF. If there is no playlist, the %3F is normal: it's just the input "?" url-encoded. The server is 
returning your command intact, it was not processed (since there is no playlist). Odd maybe, but the 
"defined" behaviour...

Matt, I have added a bunch of additional debug messages to the log. They will appear in the nightly tonite 
(4015 or bigger).  May I ask that you try "mode ?" and "playlist index ?" on your system again with 
d_command and d_cli on and post the logs again? This one is giving us some trouble...
Comment 6 Matt Wald 2005-08-21 11:27:53 UTC
Will do, and sorry if I'm not explaining it very well.  A couple of other 
things that might not relate but I'll tell you about it anyway in case it does:

1) My polling program sends update queries to the CLI pretty rapidly - is it 
possible that the address being appended sometimes at the end of the response 
(it's not alway an address by the way) is actually the address that is being 
sent for a new command?  Some sort of buffer overrun or something?
2) Also, I've noticed that when the CLI polling program is running that it 
will cause songs to be skipped in the playlist and start back at index 0.

Will send you new logs.
Comment 7 Fred 2005-08-21 12:44:47 UTC
For the rapid CLI firing skipping songs, yes it is possible. The same happens on the web interface *except* 
with the refreshing and stuff you never have the chance to go fast enough...

As for the rapid CLI firing, that's why I wanted to see the program *BUT* you tell me you also get that with 
Telnet... and there you can't go very fast.

I will nevertheless try to overload the CLI see what happens...
Comment 8 Matt Wald 2005-08-21 16:03:10 UTC
Fred, I've confirmed today that when I turn off the CLI polling program the 
tracks that used to skip to index 0 in the playlist no longer do.  Also, in 
thinking more about how I recreated this through telnet, I realized that the 
polling program was probably still running on another device at the time I 
entered the command through telnet.  I'm now playing around with varying the 
time delay for sending commands ...
Comment 9 Fred 2005-08-21 17:06:37 UTC
Well, please try that, it might help. In my programs, I do tend to wait for the server reply before sending a 
new command, but that would require extensive changes in yours. In that configuration it works :-)

Nevertheless, it should work no matter how quick you are sending things. I found something strange in 
the parsing that I fixed, but I am not sure it applies since from the log below it seems the parsing is OK...

Let us try again with the latest nightly and contine from there. 
Comment 10 Blackketter Dean 2005-08-21 17:30:55 UTC
Fred, Matt:  I'm not quite following here.  Is this still an issue?  If so, should we consider it for 6.2 (beta 
end of this month, final end of Sept.)
Comment 11 Fred 2005-08-21 17:33:05 UTC
6.2 for sure, I want that fixed in the 6.1.x nightlies before that.
Comment 12 Matt Wald 2005-08-22 05:23:15 UTC
Well it looks to me like 4030 may have cured the problem.  I can't recreate 
the behavior on two of the tracks that consistently skipped, and I can't get 
any responses with ? even when the polling program is at its original speed. 
(Incidentally, the CLI responses are faster now, which is great.) I'll keep 
testing to make sure but it looks like you got it.  Great job!  And thanks 
alot.