You are here: Home » Topic » Remote DAAP share / tunneling suddenly not working

Remote DAAP share / tunneling suddenly not working

FireFly Media Server (formerly mt-daapd) Firefly Media Server Forums Firefly Media Server Nightlies Feedback Remote DAAP share / tunneling suddenly not working

This topic contains 16 replies, has 2 voices, and was last updated by  rpedde 10 years, 7 months ago.

Viewing 15 posts - 1 through 15 (of 17 total)
  • Author
    Posts
  • #1297

    jamiew
    Participant

    Posted this on the Wiki discussion here: http://wiki.mt-daapd.org/wiki/Talk:SSH_Tunnel

    My beloved daap_connect one-liner ceased to function recently, with the only genuine change being an upgrade on my computer iTunes 7.1 from iTunes 7.


    ssh [email protected] -N -f -L 3689:servername.com:3689 && mDNSProxyResponderPosix 127.0.0.1 musicserver "Music Server" _daap._tcp. 3689 &

    It tries to connect and just defaults back to the Library. Firewalls all look OK. Don’t see anything interesting in the logs about it… anybody have any ideas?[/code]

    #10133

    rpedde
    Participant

    @jamiew wrote:

    Posted this on the Wiki discussion here: http://wiki.mt-daapd.org/wiki/Talk:SSH_Tunnel

    My beloved daap_connect one-liner ceased to function recently, with the only genuine change being an upgrade on my computer iTunes 7.1 from iTunes 7.


    ssh [email protected] -N -f -L 3689:servername.com:3689 && mDNSProxyResponderPosix 127.0.0.1 musicserver "Music Server" _daap._tcp. 3689 &

    It tries to connect and just defaults back to the Library. Firewalls all look OK. Don’t see anything interesting in the logs about it… anybody have any ideas?[/code]

    Maybe use a different port than 3689? iTunes seems pretty aggressive in listening on it.:

    ssh [email protected] -N -f -L 9999:localhost:3689 && mDNSProxyResponderPosix 127.0.0.1 musicserver “Music Server” _daap._tcp 9999 &

    Also, make sure you aren’t running a copy of the server on the local machine either.

    Might be worth running ssh in -vv to see dbug messages about what’s up, too.

    — Ron

    #10134

    jamiew
    Participant

    Thanks for the tips. Still no go : Some debug info in case it might help, I can’t make anything of it; notes are in parens.


    ((((ssh connecting...))))
    debug2: fd 5 setting O_NONBLOCK
    debug1: channel 1: new [port listener]
    debug1: Entering interactive session.
    ((((ssh connected now; the mDNSProxyResponder command executes...))))
    Made Service Records for BLACKHOLE32Music32Server._daap._tcp.local.
    Service BLACKHOLE32Music32Server._daap._tcp.local. now registered and active
    debug1: Connection to port 9999 forwarding to servername.org port 3689 requested.

    ((((now I click on "BLACKHOLE Music Server" in iTunes 7...))))
    debug2: fd 6 setting TCP_NODELAY
    debug1: channel 2: new [direct-tcpip]
    debug2: channel 2: open confirm rwindow 131072 rmax 32768
    debug2: channel 2: window 49152 sent adjust 81920
    debug2: channel 2: window 49152 sent adjust 81920
    debug2: channel 2: window 49152 sent adjust 81920
    (...many of these...)
    debug2: channel 2: window 49152 sent adjust 81920
    debug2: channel 2: rcvd eof
    debug2: channel 2: output open -> drain
    debug2: channel 2: obuf empty
    debug2: channel 2: close_write
    debug2: channel 2: output drain -> closed
    debug2: channel 2: read<=0 rfd 6 len 0
    debug2: channel 2: read failed
    debug2: channel 2: close_read
    debug2: channel 2: input open -> drain
    debug2: channel 2: ibuf empty
    debug2: channel 2: send eof
    debug2: channel 2: input drain -> closed
    debug2: channel 2: send close
    debug2: channel 2: rcvd close
    debug2: channel 2: is dead
    debug2: channel 2: garbage collecting
    debug1: channel 2: free: direct-tcpip: listening port 9999 for servername.org port 3689, connect from 127.0.0.1 port 52696, nchannels 3

    Here is some small debug output on console.log from mDNSProxyResponder:


    Apr 17 12:04:49 jdubtop mDNSResponder: mDNS_Deregister_internal: Record BFFFDA40 musicserver.local. (Addr) not found in list
    Apr 17 12:04:49 jdubtop mDNSResponder: mDNS_Deregister_internal: Record BFFFDA40 musicserver.local. (Addr) not found in list
    Apr 17 12:04:49 jdubtop mDNSResponder: mDNS_Deregister_internal: Record BFFFDF5C 1.0.0.127.in-addr.arpa. (PTR) not found in list

    Is there any way to get debug output from iTunes?

    #10135

    rpedde
    Participant

    @jamiew wrote:

    Thanks for the tips. Still no go : Some debug info in case it might help, I can’t make anything of it; notes are in parens.

    That’s about all I make out of it either. And this is really openssh, not some other ssh client?

    And what’s the remote machine? It’s not a mac is it? And if it is, is it running iTunes? I’m wondering if maybe it’s grabbed the port already.

    Is there any way to get debug output from iTunes?

    Lol.

    — Ron

    #10136

    jamiew
    Participant

    I’m running a mac, openssh 4.5 (stock)

    Remote machine is FreeBSD, no iTunes. I’m able to hit the mt-daapd webserver at 3689, config says it’s running on 3689

    Just realized the server is running the mt-daapd version from the ports collection, Version 0.2.4. Should I upgrade to a nightly, or would that make it worse? 🙂

    Thanks for the help

    #10137

    rpedde
    Participant

    @jamiew wrote:

    I’m running a mac, openssh 4.5 (stock)

    Remote machine is FreeBSD, no iTunes. I’m able to hit the mt-daapd webserver at 3689, config says it’s running on 3689

    Just realized the server is running the mt-daapd version from the ports collection, Version 0.2.4. Should I upgrade to a nightly, or would that make it worse? 🙂

    Thanks for the help

    svn-1489 would be okay, current nightlies are a bit in flux.

    Does it take a long time before it dies, or does it just disconnect immediately, as soon as you click on it?

    #10138

    jamiew
    Participant

    I posted a reply earlier but it appears it didn’t go through. There’s several seconds before iTunes errors out and switches me back to the Library. Listening to ssh a few packets fly by, maybe a few every second

    I listened to ssh on the remote server and got this:


    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_input_channel_open: ctype direct-tcpip rchan 2 win 131072 max 32768
    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_request_direct_tcpip: originator 127.0.0.1 port 56477, target server.domain.com port 3689
    Apr 17 21:06:05 colossus sshd[14347]: debug1: channel 0: new [direct-tcpip]
    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_input_channel_open: confirm direct-tcpip
    Apr 17 21:06:05 colossus sshd[14347]: debug1: channel 0: connected
    Apr 17 21:06:17 colossus sshd[14347]: debug1: channel 0: free: direct-tcpip, nchannels 1

    Is there a way to turn up the mt-daapd Loglevel?

    #10139

    rpedde
    Participant

    @jamiew wrote:

    I posted a reply earlier but it appears it didn’t go through. There’s several seconds before iTunes errors out and switches me back to the Library. Listening to ssh a few packets fly by, maybe a few every second

    I listened to ssh on the remote server and got this:


    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_input_channel_open: ctype direct-tcpip rchan 2 win 131072 max 32768
    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_request_direct_tcpip: originator 127.0.0.1 port 56477, target server.domain.com port 3689
    Apr 17 21:06:05 colossus sshd[14347]: debug1: channel 0: new [direct-tcpip]
    Apr 17 21:06:05 colossus sshd[14347]: debug1: server_input_channel_open: confirm direct-tcpip
    Apr 17 21:06:05 colossus sshd[14347]: debug1: channel 0: connected
    Apr 17 21:06:17 colossus sshd[14347]: debug1: channel 0: free: direct-tcpip, nchannels 1

    Is there a way to turn up the mt-daapd Loglevel?

    Maybe… you can do -d10, and it will probably log all read and write sizes to the socket. It blows out a lot of crap, though.

    — Ron

    #10140

    jamiew
    Participant

    So it’s definitely talking to mt-daapd. From the logfile:


    ((((( TONS of "adding mikd" type lines that my buffer didn't catch)))
    2007-04-17 23:10:04: Freeing mikd
    2007-04-17 23:10:04: Freeing miid
    2007-04-17 23:10:04: Freeing mlit
    2007-04-17 23:10:04: Freeing mikd
    2007-04-17 23:10:04: Freeing miid
    ((((( TONS like this ))))))
    2007-04-17 23:10:04: Freeing mlit
    2007-04-17 23:10:04: Freeing mikd
    2007-04-17 23:10:04: Freeing miid
    2007-04-17 23:10:04: Freeing mlit
    2007-04-17 23:10:04: Freeing mikd
    2007-04-17 23:10:04: Freeing miid
    2007-04-17 23:10:04: Entering config_set_status
    2007-04-17 23:10:04: Exiting config_set_status
    2007-04-17 23:10:04: Bad request -- root=0, streaming=0
    2007-04-17 23:10:04: Thread 0: Entering ws_returnerror (400: Invalid Request)
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_emitheaders
    2007-04-17 23:10:04: Emitting reponse header Content-Type: application/x-dmap-tagged
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Emitting reponse header DAAP-Server: mt-daapd/0.2.4
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Emitting reponse header Accept-Ranges: bytes
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Emitting reponse header Date: Wed, 18 Apr 2007 03:10:04 GMT
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Exitin ws_emitheaders
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Entering ws_writefd
    2007-04-17 23:10:04: Exiting ws_writefd
    2007-04-17 23:10:04: Exiting ws_returnerror
    2007-04-17 23:10:04: Entering config_set_status
    2007-04-17 23:10:04: Exiting config_set_status
    2007-04-17 23:10:04: Entering ws_close
    2007-04-17 23:10:04: Entering config_set_status
    2007-04-17 23:10:04: Exiting config_set_status
    2007-04-17 23:10:04: Thread 0: Terminating
    2007-04-17 23:10:04: Thread 0: Freeing request headers
    2007-04-17 23:10:04: Entering ws_freearglist
    2007-04-17 23:10:04: Exiting ws_freearglist
    2007-04-17 23:10:04: Thread 0: Freeing response headers
    2007-04-17 23:10:04: Entering ws_freearglist
    2007-04-17 23:10:04: Exiting ws_freearglist
    2007-04-17 23:10:04: Thread 0: Freeing request vars
    2007-04-17 23:10:04: Entering ws_freearglist
    2007-04-17 23:10:04: Exiting ws_freearglist
    2007-04-17 23:10:04: Thread 0: Closing fd
    2007-04-17 23:10:04: Entering ws_remove_dispatch_thread
    2007-04-17 23:10:04: With thread 0 exiting, 0 are still running
    2007-04-17 23:10:04: Exiting ws_remote_dispatch_thread
    2007-04-17 23:10:04: Exiting ws_close (thread terminating)
    2007-04-17 23:10:23: select(6, 0.005859)
    2007-04-17 23:10:23: select(6, 0.099609)
    2007-04-17 23:10:23: select(6, 0.099609)
    2007-04-17 23:10:23: select(6, 1965535.874023)

    Then iTunes just defaults back to the Library, no messages.

    #10141

    rpedde
    Participant

    @jamiew wrote:

    So it’s definitely talking to mt-daapd. From the logfile:

    And it definitely works locally, right?

    Is it a localhost issue with the new iTunes? Like, if you make the connection from another machine rather than via localhost?

    #10142

    jamiew
    Participant

    mt-daapd running on my local mac on port 3689 or 9999 works fine.

    currently shopping around for a commandline DAAP client to test my music server locally, do you have any suggestions?

    #10143

    jamiew
    Participant

    OK, more updates. running dapple/itshell on the remote music server i *am* able to connect to the local mt-daapd install, get a list of playlists, search for files, and fetch URLs for daap entries, but issuing a get command does not work. For posterity’s sake, the itshell session:


    iTunes>find Ratatat
    7371823 : Kennedy, Ratatat (Classics)
    7371829 : Tacobel Canon, Ratatat (Classics)
    7371820 : Tropicana, Ratatat (Classics)
    7371852 : Wildcat, Ratatat (Classics)
    7371844 : Lex, Ratatat (Classics)
    7371794 : Tropicana, Ratatat (Classics)
    iTunes>url 7371823
    daap://localhost:3689/resolve?database-spec='dmap.persistentid:0x0000000000000000'&song-spec='dmap.persistentid:0x0000000000000000'
    iTunes>get 7371823
    Fetching 7371823 : Kennedy, Ratatat (Classics)
    http://localhost:3689/databases/1/items/7371823.mp3?session-id=3&revision-number=840766
    Setting error to Broken response (content type audio/mp3) on http://localhost:3689/databases/1/items/7371823.mp3?session-id=3&revision-number=840766 at /usr/local/lib/perl5/site_perl/5.8.8/Net/DAAP/Client.pm line 652.
    Setting error to skipped: 7371823 at /usr/local/lib/perl5/site_perl/5.8.8/Net/DAAP/Client.pm line 652.
    Failed: skipped: 7371823

    I think this may just be dapple being broken as it hasn’t been updated in a while. Output from mt-daapd, -d3


    2007-04-19 12:59:24: Starting web server from /usr/local/share/mt-daapd/admin-root on port 3689
    2007-04-19 12:59:24: Registering rendezvous names
    2007-04-19 12:59:24: Scanned 10134 songs in 14 seconds
    2007-04-19 12:59:31: Thread 0: could not read: No such file or directory
    2007-04-19 12:59:40: Thread 1: could not read: Unknown error: 0
    2007-04-19 12:59:41: Thread 2: could not read: Unknown error: 0
    2007-04-19 13:00:12: Session 3: Streaming file '07 Kennedy.mp3' to 127.0.0.1 (offset 0)

    So all appears well…

    Doing another request from iTunes 7 on my mac:


    2007-04-19 13:05:03: Unknown meta code: daap.songalbumartist
    2007-04-19 13:05:03: Unknown meta code: daap.songcodectype
    2007-04-19 13:05:03: Unknown meta code: daap.songcodecsubtype
    2007-04-19 13:05:03: Unknown meta code: daap.songcontentrating
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.norm-volume
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-songid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-artistid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-playlistid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-composerid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-genreid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.itms-storefrontid
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.has-video
    2007-04-19 13:05:03: Unknown meta code: daap.songcategory
    2007-04-19 13:05:03: Unknown meta code: daap.songextradata
    2007-04-19 13:05:03: Unknown meta code: daap.songcontentdescription
    2007-04-19 13:05:03: Unknown meta code: daap.songlongcontentdescription
    2007-04-19 13:05:03: Unknown meta code: daap.songkeywords
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.is-podcast
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.mediakind
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.series-name
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.network-name
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.episode-num-str
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.episode-sort
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.season-num
    2007-04-19 13:05:03: Unknown meta code: daap.songgapless
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.gapless-enc-del
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.gapless-heur
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.gapless-enc-dr
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.gapless-dur
    2007-04-19 13:05:03: Unknown meta code: com.apple.itunes.gapless-resy
    2007-04-19 13:05:11: Thread 5: Entering ws_returnerror (400: Invalid Request)

    Which seems new! Any thoughts? Do you have tunneling working with iTunes 7?

    #10144

    rpedde
    Participant

    @jamiew wrote:

    iTunes>url 7371823
    daap://localhost:3689/resolve?database-spec=’dmap.persistentid:0x0000000000000000’&song-spec=’dmap.persistentid:0x0000000000000000′

    Aaaah… I had to turn on persistant ids to get xbmc working, but apparently that breaks that particular app. I don’t implement a resolve request.. Guess I’ll have to find out what that’s *supposed* to return.


    iTunes>get 7371823
    Fetching 7371823 : Kennedy, Ratatat (Classics)
    http://localhost:3689/databases/1/items/7371823.mp3?session-id=3&revision-number=840766
    Setting error to Broken response (content type audio/mp3) on http://localhost:3689/databases/1/items/7371823.mp3?session-id=3&revision-number=840766 at /usr/local/lib/perl5/site_perl/5.8.8/Net/DAAP/Client.pm line 652.
    Setting error to skipped: 7371823 at /usr/local/lib/perl5/site_perl/5.8.8/Net/DAAP/Client.pm line 652.
    Failed: skipped: 7371823

    Hrm… that should work, though.

    2007-04-19 13:05:11: Thread 5: Entering ws_returnerror (400: Invalid Request)

    can you turn up the debug and see what the request is that it was asking for?

    I’ll try and set up a tunnel myself to test.

    #10145

    jamiew
    Participant

    2007-04-19 16:01:06: Got entry for 18 Red Velvet.mp3
    ...
    2007-04-19 16:01:06: Got entry for Jeff Buckley -- Hallelujah.mp3
    2007-04-19 16:01:06: Successfully enumerated database - 10134 items
    2007-04-19 16:01:06: Exiting daap_response_songlist
    2007-04-19 16:01:06: Entering config_set_status
    2007-04-19 16:01:06: Exiting config_set_status
    2007-04-19 16:01:06: Satisfying request
    2007-04-19 16:01:06: Serializing
    2007-04-19 16:01:06: Added *Content-Length=3191062*
    2007-04-19 16:01:06: Emitting headers
    2007-04-19 16:01:06: Emitting reponse header Content-Length: 3191062
    2007-04-19 16:01:06: Emitting reponse header Content-Type: application/x-dmap-tagged
    2007-04-19 16:01:06: Emitting reponse header DAAP-Server: mt-daapd/0.2.4
    2007-04-19 16:01:06: Emitting reponse header Accept-Ranges: bytes
    2007-04-19 16:01:06: Emitting reponse header Date: Thu, 19 Apr 2007 20:01:04 GMT
    2007-04-19 16:01:10: Sent 3191062 bytes in 4 seconds
    2007-04-19 16:01:10: Done, freeing
    2007-04-19 16:01:10: Finished serving DAAP response
    2007-04-19 16:01:10: Entering config_set_status
    2007-04-19 16:01:10: Exiting config_set_status
    2007-04-19 16:01:10: Thread 0: Terminating
    2007-04-19 16:01:10: Thread 0: Freeing request headers
    2007-04-19 16:01:10: Thread 0: Freeing response headers
    2007-04-19 16:01:10: Thread 0: Freeing request vars
    2007-04-19 16:01:10: Thread 0: got request
    2007-04-19 16:01:10: Request: GET /databases/1/containers?meta=dmap.itemid,dmap.itemname,dmap.persistentid,com.apple.itunes.smart-playlist,dmap.haschildcontainers,dmap.parentcontainerid,com.apple.itunes.is-podcast-playlist,com.apple.itunes.special-playlist&revision-number=33315 HTTP/1.1
    2007-04-19 16:01:10: Thread 0: Read: Host: 127.0.0.1
    2007-04-19 16:01:10: Thread 0: Adding header *Host=127.0.0.1*
    2007-04-19 16:01:10: Added *Host=127.0.0.1*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Version: 3.5
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Version=3.5*
    2007-04-19 16:01:10: Added *Client-DAAP-Version=3.5*
    2007-04-19 16:01:10: Thread 0: Read: User-Agent: iTunes/7.1.1 (Macintosh; N; Intel)
    2007-04-19 16:01:10: Thread 0: Adding header *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:10: Added *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:10: Thread 0: Read: Accept-Language: en-us, en;q=0.50
    2007-04-19 16:01:10: Thread 0: Adding header *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:10: Added *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Access-Index: 2
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:10: Added *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Validation: 0111110C9C9F14456E0A5690BA090E92
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Validation=0111110C9C9F14456E0A5690BA090E92*
    2007-04-19 16:01:10: Added *Client-DAAP-Validation=0111110C9C9F14456E0A5690BA090E92*
    2007-04-19 16:01:10: Thread 0: Read: Accept-Encoding: gzip
    2007-04-19 16:01:10: Thread 0: Adding header *Accept-Encoding=gzip*
    2007-04-19 16:01:10: Added *Accept-Encoding=gzip*
    2007-04-19 16:01:10: Thread 0: Read:
    2007-04-19 16:01:10: Thread 0: Headers parsed!
    2007-04-19 16:01:10: Checking to see if connection matches close
    2007-04-19 16:01:10: Thread 0: Connection type HTTP/1.1
    : Connection: persist
    2007-04-19 16:01:10: Thread 0: parsing GET args
    2007-04-19 16:01:10: Thread 0: Entering ws_getgetvars (meta=dmap.itemid,dmap.itemname,dmap.persistentid,com.apple.itunes.smart-playlist,dmap.haschildcontainers,dmap.parentcontainerid,com.apple.itunes.is-podcast-playlist,com.apple.itunes.special-playlist&revision-number=33315)
    2007-04-19 16:01:10: Thread 0: Adding arg meta = dmap.itemid,dmap.itemname,dmap.persistentid,com.apple.itunes.smart-playlist,dmap.haschildcontainers,dmap.parentcontainerid,com.apple.itunes.is-podcast-playlist,com.apple.itunes.special-playlist
    2007-04-19 16:01:10: Added *meta=dmap.itemid,dmap.itemname,dmap.persistentid,com.apple.itunes.smart-playlist,dmap.haschildcontainers,dmap.parentcontainerid,com.apple.itunes.is-podcast-playlist,com.apple.itunes.special-playlist*
    2007-04-19 16:01:10: Thread 0: Adding arg revision-number = 33315
    2007-04-19 16:01:10: Added *revision-number=33315*
    2007-04-19 16:01:10: Thread 0: Done parsing GET/POST args!
    2007-04-19 16:01:10: Thread 0: Original URI: /databases/1/containers
    2007-04-19 16:01:10: Thread 0: Translated URI: /databases/1/containers
    2007-04-19 16:01:10: Thread 0: Preparing to find handler
    2007-04-19 16:01:10: Thread 0: URI Match!
    2007-04-19 16:01:10: Thread 0: Time is 1177012870 seconds after epoch
    2007-04-19 16:01:10: Thread 0: Setting time header
    2007-04-19 16:01:10: Added *Date=Thu, 19 Apr 2007 20:01:10 GMT*
    2007-04-19 16:01:10: Thread 0: Using non-default handler
    2007-04-19 16:01:10: Added *Accept-Ranges=bytes*
    2007-04-19 16:01:10: Added *DAAP-Server=mt-daapd/0.2.4*
    2007-04-19 16:01:10: Added *Content-Type=application/x-dmap-tagged*
    2007-04-19 16:01:10: Preparing to send playlists
    2007-04-19 16:01:10: Returning playlist 6927094
    2007-04-19 16:01:10: -- Songs: 43
    2007-04-19 16:01:10: -- Smart: No
    ...
    2007-04-19 16:01:10: Returning playlist 5
    2007-04-19 16:01:10: -- Songs: 872
    2007-04-19 16:01:10: -- Smart: Yes
    2007-04-19 16:01:10: Entering config_set_status
    2007-04-19 16:01:10: Exiting config_set_status
    2007-04-19 16:01:10: Satisfying request
    2007-04-19 16:01:10: Serializing
    2007-04-19 16:01:10: Added *Content-Length=4982*
    2007-04-19 16:01:10: Emitting headers
    2007-04-19 16:01:10: Emitting reponse header Content-Length: 4982
    2007-04-19 16:01:10: Emitting reponse header Content-Type: application/x-dmap-tagged
    2007-04-19 16:01:10: Emitting reponse header DAAP-Server: mt-daapd/0.2.4
    2007-04-19 16:01:10: Emitting reponse header Accept-Ranges: bytes
    2007-04-19 16:01:10: Emitting reponse header Date: Thu, 19 Apr 2007 20:01:10 GMT
    2007-04-19 16:01:10: Sent 4982 bytes in 0 seconds
    2007-04-19 16:01:10: Done, freeing
    2007-04-19 16:01:10: Finished serving DAAP response
    2007-04-19 16:01:10: Entering config_set_status
    2007-04-19 16:01:10: Exiting config_set_status
    2007-04-19 16:01:10: Thread 0: Terminating
    2007-04-19 16:01:10: Thread 0: Freeing request headers
    2007-04-19 16:01:10: Thread 0: Freeing response headers
    2007-04-19 16:01:10: Thread 0: Freeing request vars
    2007-04-19 16:01:10: Thread 0: got request
    2007-04-19 16:01:10: Request: GET /databases/1/containers/1/items?type=music&meta=dmap.itemkind,dmap.itemid,dmap.containeritemid&revision-number=33315 HTTP/1.1
    2007-04-19 16:01:10: Thread 0: Read: Host: 127.0.0.1
    2007-04-19 16:01:10: Thread 0: Adding header *Host=127.0.0.1*
    2007-04-19 16:01:10: Added *Host=127.0.0.1*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Version: 3.5
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Version=3.5*
    2007-04-19 16:01:10: Added *Client-DAAP-Version=3.5*
    2007-04-19 16:01:10: Thread 0: Read: User-Agent: iTunes/7.1.1 (Macintosh; N; Intel)
    2007-04-19 16:01:10: Thread 0: Adding header *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:10: Added *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:10: Thread 0: Read: Accept-Language: en-us, en;q=0.50
    2007-04-19 16:01:10: Thread 0: Adding header *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:10: Added *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Access-Index: 2
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:10: Added *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:10: Thread 0: Read: Client-DAAP-Validation: 45F05333DDA0054103CC92D2ACB05FC9
    2007-04-19 16:01:10: Thread 0: Adding header *Client-DAAP-Validation=45F05333DDA0054103CC92D2ACB05FC9*
    2007-04-19 16:01:10: Added *Client-DAAP-Validation=45F05333DDA0054103CC92D2ACB05FC9*
    2007-04-19 16:01:10: Thread 0: Read: Accept-Encoding: gzip
    2007-04-19 16:01:10: Thread 0: Adding header *Accept-Encoding=gzip*
    2007-04-19 16:01:10: Added *Accept-Encoding=gzip*
    2007-04-19 16:01:10: Thread 0: Read:
    2007-04-19 16:01:10: Thread 0: Headers parsed!
    2007-04-19 16:01:10: Checking to see if connection matches close
    2007-04-19 16:01:10: Thread 0: Connection type HTTP/1.1
    : Connection: persist
    2007-04-19 16:01:10: Thread 0: parsing GET args
    2007-04-19 16:01:10: Thread 0: Entering ws_getgetvars (type=music&meta=dmap.itemkind,dmap.itemid,dmap.containeritemid&revision-number=33315)
    2007-04-19 16:01:10: Thread 0: Adding arg type = music
    2007-04-19 16:01:10: Added *type=music*
    2007-04-19 16:01:10: Thread 0: Adding arg meta = dmap.itemkind,dmap.itemid,dmap.containeritemid
    2007-04-19 16:01:10: Added *meta=dmap.itemkind,dmap.itemid,dmap.containeritemid*
    2007-04-19 16:01:10: Thread 0: Adding arg revision-number = 33315
    2007-04-19 16:01:10: Added *revision-number=33315*
    2007-04-19 16:01:10: Thread 0: Done parsing GET/POST args!
    2007-04-19 16:01:10: Thread 0: Original URI: /databases/1/containers/1/items
    2007-04-19 16:01:10: Thread 0: Translated URI: /databases/1/containers/1/items
    2007-04-19 16:01:10: Thread 0: Preparing to find handler
    2007-04-19 16:01:10: Thread 0: URI Match!
    2007-04-19 16:01:10: Thread 0: Time is 1177012870 seconds after epoch
    2007-04-19 16:01:10: Thread 0: Setting time header
    2007-04-19 16:01:10: Added *Date=Thu, 19 Apr 2007 20:01:10 GMT*
    2007-04-19 16:01:10: Thread 0: Using non-default handler
    2007-04-19 16:01:10: Added *Accept-Ranges=bytes*
    2007-04-19 16:01:10: Added *DAAP-Server=mt-daapd/0.2.4*
    2007-04-19 16:01:10: Added *Content-Type=application/x-dmap-tagged*
    2007-04-19 16:01:10: meta codes: 21
    2007-04-19 16:01:10: Preparing to send playlist items for pl #1
    2007-04-19 16:01:11: Sucessfully enumerated 10134 items
    2007-04-19 16:01:11: Entering config_set_status
    2007-04-19 16:01:11: Exiting config_set_status
    2007-04-19 16:01:11: Satisfying request
    2007-04-19 16:01:11: Serializing
    2007-04-19 16:01:11: Added *Content-Length=415555*
    2007-04-19 16:01:11: Emitting headers
    2007-04-19 16:01:11: Emitting reponse header Content-Length: 415555
    2007-04-19 16:01:11: Emitting reponse header Content-Type: application/x-dmap-tagged
    2007-04-19 16:01:11: Emitting reponse header DAAP-Server: mt-daapd/0.2.4
    2007-04-19 16:01:11: Emitting reponse header Accept-Ranges: bytes
    2007-04-19 16:01:11: Emitting reponse header Date: Thu, 19 Apr 2007 20:01:10 GMT
    2007-04-19 16:01:11: Sent 415555 bytes in 0 seconds
    2007-04-19 16:01:11: Done, freeing
    2007-04-19 16:01:11: Finished serving DAAP response
    2007-04-19 16:01:11: Entering config_set_status
    2007-04-19 16:01:11: Exiting config_set_status
    2007-04-19 16:01:11: Thread 0: Terminating
    2007-04-19 16:01:11: Thread 0: Freeing request headers
    2007-04-19 16:01:11: Thread 0: Freeing response headers
    2007-04-19 16:01:11: Thread 0: Freeing request vars
    2007-04-19 16:01:11: Thread 0: got request
    2007-04-19 16:01:11: Request: GET /databases/1/containers/6927094/items?type=music&meta=dmap.itemkind,dmap.itemid,dmap.containeritemid&revision-number=33315 HTTP/1.1
    2007-04-19 16:01:11: Thread 0: Read: Host: 127.0.0.1
    2007-04-19 16:01:11: Thread 0: Adding header *Host=127.0.0.1*
    2007-04-19 16:01:11: Added *Host=127.0.0.1*
    2007-04-19 16:01:11: Thread 0: Read: Client-DAAP-Version: 3.5
    2007-04-19 16:01:11: Thread 0: Adding header *Client-DAAP-Version=3.5*
    2007-04-19 16:01:11: Added *Client-DAAP-Version=3.5*
    2007-04-19 16:01:11: Thread 0: Read: User-Agent: iTunes/7.1.1 (Macintosh; N; Intel)
    2007-04-19 16:01:11: Thread 0: Adding header *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:11: Added *User-Agent=iTunes/7.1.1 (Macintosh; N; Intel)*
    2007-04-19 16:01:11: Thread 0: Read: Accept-Language: en-us, en;q=0.50
    2007-04-19 16:01:11: Thread 0: Adding header *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:11: Added *Accept-Language=en-us, en;q=0.50*
    2007-04-19 16:01:11: Thread 0: Read: Client-DAAP-Access-Index: 2
    2007-04-19 16:01:11: Thread 0: Adding header *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:11: Added *Client-DAAP-Access-Index=2*
    2007-04-19 16:01:11: Thread 0: Read: Client-DAAP-Validation: 590026DCEC3C3324A44EB7550BFC16B0
    2007-04-19 16:01:11: Thread 0: Adding header *Client-DAAP-Validation=590026DCEC3C3324A44EB7550BFC16B0*
    2007-04-19 16:01:11: Added *Client-DAAP-Validation=590026DCEC3C3324A44EB7550BFC16B0*
    2007-04-19 16:01:11: Thread 0: Read: Accept-Encoding: gzip
    2007-04-19 16:01:11: Thread 0: Adding header *Accept-Encoding=gzip*
    2007-04-19 16:01:11: Added *Accept-Encoding=gzip*
    2007-04-19 16:01:11: Thread 0: Read:
    2007-04-19 16:01:11: Thread 0: Headers parsed!
    2007-04-19 16:01:11: Checking to see if connection matches close
    2007-04-19 16:01:11: Thread 0: Connection type HTTP/1.1
    : Connection: persist
    2007-04-19 16:01:11: Thread 0: parsing GET args
    2007-04-19 16:01:11: Thread 0: Entering ws_getgetvars (type=music&meta=dmap.itemkind,dmap.itemid,dmap.containeritemid&revision-number=33315)
    2007-04-19 16:01:11: Thread 0: Adding arg type = music
    2007-04-19 16:01:11: Added *type=music*
    2007-04-19 16:01:11: Thread 0: Adding arg meta = dmap.itemkind,dmap.itemid,dmap.containeritemid
    2007-04-19 16:01:11: Added *meta=dmap.itemkind,dmap.itemid,dmap.containeritemid*
    2007-04-19 16:01:11: Thread 0: Adding arg revision-number = 33315
    2007-04-19 16:01:11: Added *revision-number=33315*
    2007-04-19 16:01:11: Thread 0: Done parsing GET/POST args!
    2007-04-19 16:01:11: Thread 0: Original URI: /databases/1/containers/6927094/items
    2007-04-19 16:01:11: Thread 0: Translated URI: /databases/1/containers/6927094/items
    2007-04-19 16:01:11: Thread 0: Preparing to find handler
    2007-04-19 16:01:11: Thread 0: URI Match!
    2007-04-19 16:01:11: Thread 0: Time is 1177012871 seconds after epoch
    2007-04-19 16:01:11: Thread 0: Setting time header
    2007-04-19 16:01:11: Added *Date=Thu, 19 Apr 2007 20:01:11 GMT*
    2007-04-19 16:01:11: Thread 0: Using non-default handler
    2007-04-19 16:01:11: Added *Accept-Ranges=bytes*
    2007-04-19 16:01:11: Added *DAAP-Server=mt-daapd/0.2.4*
    2007-04-19 16:01:11: Added *Content-Type=application/x-dmap-tagged*
    2007-04-19 16:01:11: meta codes: 21
    2007-04-19 16:01:11: Preparing to send playlist items for pl #6927094
    2007-04-19 16:01:11: Could not find id 6905337
    2007-04-19 16:01:11: Adding itemid 6905339
    2007-04-19 16:01:11: Adding itemid 6905340
    2007-04-19 16:01:11: Adding itemid 6905341
    2007-04-19 16:01:11: Adding itemid 6905343
    2007-04-19 16:01:11: Adding itemid 6905344
    2007-04-19 16:01:11: Adding itemid 6905345
    2007-04-19 16:01:11: Adding itemid 6905346
    2007-04-19 16:01:11: Adding itemid 6905347
    2007-04-19 16:01:11: Adding itemid 6905354
    2007-04-19 16:01:11: Adding itemid 6905357
    2007-04-19 16:01:11: Adding itemid 6905348
    2007-04-19 16:01:11: Adding itemid 6905349
    2007-04-19 16:01:11: Adding itemid 6905350
    2007-04-19 16:01:11: Adding itemid 6905351
    2007-04-19 16:01:11: Adding itemid 6905352
    2007-04-19 16:01:11: Adding itemid 6905353
    2007-04-19 16:01:11: Adding itemid 6905355
    2007-04-19 16:01:11: Adding itemid 6905356
    2007-04-19 16:01:11: Adding itemid 6905358
    2007-04-19 16:01:11: Adding itemid 6905359
    2007-04-19 16:01:11: Adding itemid 6905360
    2007-04-19 16:01:11: Could not find id 6905381
    2007-04-19 16:01:11: Adding itemid 6905361
    2007-04-19 16:01:11: Adding itemid 6905362
    2007-04-19 16:01:11: Adding itemid 6905363
    2007-04-19 16:01:11: Adding itemid 6905364
    2007-04-19 16:01:11: Adding itemid 6905365
    2007-04-19 16:01:11: Adding itemid 6905366
    2007-04-19 16:01:11: Adding itemid 6905367
    2007-04-19 16:01:11: Adding itemid 6905368
    2007-04-19 16:01:11: Adding itemid 6905369
    2007-04-19 16:01:11: Adding itemid 6905370
    2007-04-19 16:01:11: Adding itemid 6905371
    2007-04-19 16:01:11: Could not find id 6905372
    2007-04-19 16:01:11: Could not find id 6905373
    2007-04-19 16:01:11: Could not find id 6905374
    2007-04-19 16:01:11: Could not find id 6905375
    2007-04-19 16:01:11: Could not find id 6905376
    2007-04-19 16:01:11: Could not find id 6905377
    2007-04-19 16:01:11: Could not find id 6905378
    2007-04-19 16:01:11: Could not find id 6905379
    2007-04-19 16:01:11: Could not find id 6905380
    2007-04-19 16:01:11: Entering config_set_status
    2007-04-19 16:01:11: Exiting config_set_status
    2007-04-19 16:01:11: Bad request -- root=0, streaming=0
    2007-04-19 16:01:11: Thread 0: Entering ws_returnerror (400: Invalid Request)
    2007-04-19 16:01:11: Emitting reponse header Content-Type: application/x-dmap-tagged
    2007-04-19 16:01:11: Emitting reponse header DAAP-Server: mt-daapd/0.2.4
    2007-04-19 16:01:11: Emitting reponse header Accept-Ranges: bytes
    2007-04-19 16:01:11: Emitting reponse header Date: Thu, 19 Apr 2007 20:01:11 GMT
    2007-04-19 16:01:11: Entering config_set_status
    2007-04-19 16:01:11: Exiting config_set_status
    2007-04-19 16:01:11: Entering config_set_status
    2007-04-19 16:01:11: Exiting config_set_status
    2007-04-19 16:01:11: Thread 0: Terminating
    2007-04-19 16:01:11: Thread 0: Freeing request headers
    2007-04-19 16:01:11: Thread 0: Freeing response headers
    2007-04-19 16:01:11: Thread 0: Freeing request vars
    2007-04-19 16:01:11: Thread 0: Closing fd
    2007-04-19 16:01:11: With thread 0 exiting, 0 are still running
    #10146

    rpedde
    Participant

    D’oh!

    I think that’s a long standing issue when there is a playlist with no entries. Or something. It clealy doesn’t like that playlist (6927094).

    If you look at the log further up, you should see where that playlist gets added. See what it is, and move it out of the scanners way. See if that does it.

    — Ron

Viewing 15 posts - 1 through 15 (of 17 total)

You must be logged in to reply to this topic.