and here is my D9 log:
2007-10-30 07:14:29 (00000400): Rows: 1
2007-10-30 07:14:29 (00000400): Scanning iTunes id #5244
2007-10-30 07:14:29 (00000400): scan_path: file://localhost/E:/musik/Faith No More/Angel Dust/14 Easy.m4a
2007-10-30 07:14:29 (00000400): Mapping file://localhost/E:/musik/Faith No More/Angel Dust/14 Easy.m4a to /share/flash/data/daten/mp3/Faith No More/Angel Dust/14 Easy.m4a
2007-10-30 07:14:29 (00000400): Executing: select * from songs where path=’/share/flash/data/daten/mp3/Faith No More/Angel Dust/14 Easy.m4a’ and idx=0
2007-10-30 07:14:29 (00000400): Executing: INSERT INTO updated VALUES (3327)
2007-10-30 07:14:29 (00000400): Rows: 1
2007-10-30 07:14:29 (00000400): Executing: select count(*) from songs where path=’/share/flash/data/daten/mp3/Faith No More/Angel Dust/14 Easy.m4a’ and idx=0
2007-10-30 07:14:29 (00000400): Executing: UPDATE songs SET title=’Easy’,artist=’Faith No More’,album=’Angel Dust’,genre=’Alternative & Punk’,comment=”,type=’m4a’,composer=’Mike Patton, Jim Martin, Billy Gould, Roddy Bottum, Mike Bordin’,orchestra=”,conductor=”,grouping=”,url=”,bitrate=128,samplerate=44100,song_length=186333,file_size=3011366,year=1992,track=14,total_tracks=14,disc=1,total_discs=1,time_modified=1187951240,db_timestamp=1193724869,bpm=0,disabled=0,compilation=0,rating=0,sample_count=0,codectype=’mp4a’,album_artist=” WHERE path=’/share/flash/data/daten/mp3/Faith No More/Angel Dust/14 Easy.m4a’ and idx=0
2007-10-30 07:14:29 (00000400): Rows: 1
2007-10-30 07:14:29 (00000400): Executing: insert into updated (id) select id from songs where path=’/share/flash/data/daten/mp3/Faith No More/Angel Dust/14 Easy.m4a’ and idx=0
2007-10-30 07:14:29 (00000400): Rows: 1
2007-10-30 07:14:29 (00000400): Scanning iTunes id #5245
2007-10-30 07:14:29 (00000400): scan_path: file://localhost/E:/musik/Faith No More/Introduce Yourself/01 Faster Disco.m4a
2007-10-30 07:14:29 (00000400): Mapping file://localhost/E:/musik/Faith No More/Introduce Yourself/01 Faster Disco.m4a to /share/flash/data/daten/mp3/Faith No More/Introduce Yourself/01 Faster Disco.m4a
2007-10-30 07:14:29 (00099003): Executing: update songs set play_count=play_count + 1, time_played=1193724869 where id=10113
2007-10-30 07:14:29 (00000402): Got listen socket 13
2007-10-30 07:14:29 (00099404): Thread 611:
2007-10-30 07:14:29 (00099404): Request: GET /rsp/db/0?query=id%3D796&type=full HTTP/1.0
2007-10-30 07:14:29 (00099404): Thread 611: Read: User-Agent: Roku SoundBridge/3.0
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:29 (00099404): Added *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:29 (00099404): Thread 611: Read: Host: 192.168.1.77:3689
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *Host=192.168.1.77:3689*
2007-10-30 07:14:29 (00099404): Added *Host=192.168.1.77:3689*
2007-10-30 07:14:29 (00099404): Thread 611: Read: Accept: */*
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *Accept=*/**
2007-10-30 07:14:29 (00099404): Added *Accept=*/**
2007-10-30 07:14:29 (00099404): Thread 611: Read: Pragma: no-cache
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *Pragma=no-cache*
2007-10-30 07:14:29 (00099404): Added *Pragma=no-cache*
2007-10-30 07:14:29 (00099404): Thread 611: Read: accept-encoding: gzip
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *accept-encoding=gzip*
2007-10-30 07:14:29 (00099404): Added *accept-encoding=gzip*
2007-10-30 07:14:29 (00099404): Thread 611: Read: accept-codecs: wma,mpeg,wav,mp4a,alac
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:29 (00099404): Added *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:29 (00099404): Thread 611: Read: rsp-version: 0.1
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *rsp-version=0.1*
2007-10-30 07:14:29 (00099404): Added *rsp-version=0.1*
2007-10-30 07:14:29 (00099404): Thread 611: Read: transcode-codecs: wav,mp3
2007-10-30 07:14:29 (00099404): Thread 611: Adding header *transcode-codecs=wav,mp3*
2007-10-30 07:14:29 (00099404): Added *transcode-codecs=wav,mp3*
2007-10-30 07:14:29 (00099404): Thread 611: Read:
2007-10-30 07:14:29 (00099404): Thread 611: Headers parsed!
2007-10-30 07:14:29 (00099404): Checking to see if connection matches keep-alive
2007-10-30 07:14:29 (00099404): Nope!
2007-10-30 07:14:29 (00099404): Thread 611: Connection type HTTP/1.0
: Connection: non-persist
2007-10-30 07:14:29 (00099404): Thread 611: parsing GET args
2007-10-30 07:14:29 (00099404): Thread 611: Adding arg query = id=796
2007-10-30 07:14:29 (00099404): Added *query=id=796*
2007-10-30 07:14:29 (00099404): Thread 611: Adding arg type = full
2007-10-30 07:14:29 (00099404): Added *type=full*
2007-10-30 07:14:29 (00099404): Thread 611: Done parsing GET/POST args!
2007-10-30 07:14:29 (00099404): Thread 611: Original URI: /rsp/db/0
2007-10-30 07:14:29 (00099404): Thread 611: Translated URI: /rsp/db/0
2007-10-30 07:14:29 (00099404): Thread 611: Preparing to find handler
2007-10-30 07:14:29 (00099404): Checking /rsp/db/0 against handler for /
2007-10-30 07:14:29 (00099404): Thread 611: URI Match!
2007-10-30 07:14:29 (00099404): Thread 611: Time is 1193724869 seconds after epoch
2007-10-30 07:14:29 (00099404): Thread 611: Setting time header
2007-10-30 07:14:29 (00099404): Added *Date=Tue, 30 Oct 2007 06:14:29 GMT*
2007-10-30 07:14:29 (00099404): Added *Connection=close*
2007-10-30 07:14:29 (00099404): Added *Server=mt-daapd/svn-1695*
2007-10-30 07:14:29 (00099404): Added *Content-Type=text/html*
2007-10-30 07:14:29 (00099404): Added *Content-Language=en_us*
2007-10-30 07:14:29 (00099404): Thread 611: Using non-default handler
2007-10-30 07:14:29 (00099404): in main_auth
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Dispatching auth for /rsp/db/0 to plugin
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:29 (00099404): Checking if pw required for /rsp/db/0 as user
2007-10-30 07:14:29 (00099404): Nope
2007-10-30 07:14:29 (00099404): in main_handler
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Dispatching /rsp/db/0 to plugin
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Checking url /rsp/db/0
2007-10-30 07:14:29 (00099404): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:29 (00099404): Getting uri…
2007-10-30 07:14:29 (00099404): Mallocing privinfo…
2007-10-30 07:14:29 (00099404): Tokenizing url
2007-10-30 07:14:29 (00099404): Found 5 elements
2007-10-30 07:14:29 (00099404): Checking reponse 0
2007-10-30 07:14:29 (00099404): Checking reponse 1
2007-10-30 07:14:29 (00099404): Checking reponse 2
2007-10-30 07:14:29 (00099404): Found it! Index: 2
2007-10-30 07:14:29 (00099404): Returning token 2002
2007-10-30 07:14:29 (00099404): Entering sp_parse_phrase
2007-10-30 07:14:29 (00099404): Entering sp_parse_oexpr
2007-10-30 07:14:29 (00099404): Entering sp_parse_aexpr
2007-10-30 07:14:29 (00099404): Entering sp_parse_expr
2007-10-30 07:14:29 (00099404): Entering sp_parse_criterion
2007-10-30 07:14:29 (00099404): Entering sp_parse_int_criterion
2007-10-30 07:14:29 (00099404): Returning token 000f
2007-10-30 07:14:29 (00099404): Returning token 00fd
2007-10-30 07:14:29 (00099404): Exiting sp_parse_int_criterion (success)
2007-10-30 07:14:29 (00099404): Exiting sp_parse_criterion (success)
2007-10-30 07:14:29 (00099404): Exiting sp_parse_expr (success)
2007-10-30 07:14:29 (00099404): Exiting sp_parse_aexpr (success)
2007-10-30 07:14:29 (00099404): Exiting sp_parse_oexpr (success)
2007-10-30 07:14:29 (00099404): Exiting sp_parse_phrase (success)
2007-10-30 07:14:41 (00000402): Got listen socket 24
2007-10-30 07:14:41 (00099805): Thread 612:
2007-10-30 07:14:41 (00099805): Request: GET /rsp/db/0?query=id%3D7374&type=full HTTP/1.0
2007-10-30 07:14:41 (00099805): Thread 612: Read: User-Agent: Roku SoundBridge/3.0
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:41 (00099805): Added *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:41 (00099805): Thread 612: Read: Host: 192.168.1.77:3689
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *Host=192.168.1.77:3689*
2007-10-30 07:14:41 (00099805): Added *Host=192.168.1.77:3689*
2007-10-30 07:14:41 (00099805): Thread 612: Read: Accept: */*
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *Accept=*/**
2007-10-30 07:14:41 (00099805): Added *Accept=*/**
2007-10-30 07:14:41 (00099805): Thread 612: Read: Pragma: no-cache
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *Pragma=no-cache*
2007-10-30 07:14:41 (00099805): Added *Pragma=no-cache*
2007-10-30 07:14:41 (00099805): Thread 612: Read: accept-encoding: gzip
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *accept-encoding=gzip*
2007-10-30 07:14:41 (00099805): Added *accept-encoding=gzip*
2007-10-30 07:14:41 (00099805): Thread 612: Read: accept-codecs: wma,mpeg,wav,mp4a,alac
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:41 (00099805): Added *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:41 (00099805): Thread 612: Read: rsp-version: 0.1
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *rsp-version=0.1*
2007-10-30 07:14:41 (00099805): Added *rsp-version=0.1*
2007-10-30 07:14:41 (00099805): Thread 612: Read: transcode-codecs: wav,mp3
2007-10-30 07:14:41 (00099805): Thread 612: Adding header *transcode-codecs=wav,mp3*
2007-10-30 07:14:41 (00099805): Added *transcode-codecs=wav,mp3*
2007-10-30 07:14:41 (00099805): Thread 612: Read:
2007-10-30 07:14:41 (00099805): Thread 612: Headers parsed!
2007-10-30 07:14:41 (00099805): Checking to see if connection matches keep-alive
2007-10-30 07:14:41 (00099805): Nope!
2007-10-30 07:14:41 (00099805): Thread 612: Connection type HTTP/1.0
: Connection: non-persist
2007-10-30 07:14:41 (00099805): Thread 612: parsing GET args
2007-10-30 07:14:41 (00099805): Thread 612: Adding arg query = id=7374
2007-10-30 07:14:41 (00099805): Added *query=id=7374*
2007-10-30 07:14:41 (00099805): Thread 612: Adding arg type = full
2007-10-30 07:14:41 (00099805): Added *type=full*
2007-10-30 07:14:41 (00099805): Thread 612: Done parsing GET/POST args!
2007-10-30 07:14:41 (00099805): Thread 612: Original URI: /rsp/db/0
2007-10-30 07:14:41 (00099805): Thread 612: Translated URI: /rsp/db/0
2007-10-30 07:14:41 (00099805): Thread 612: Preparing to find handler
2007-10-30 07:14:41 (00099805): Checking /rsp/db/0 against handler for /
2007-10-30 07:14:41 (00099805): Thread 612: URI Match!
2007-10-30 07:14:41 (00099805): Thread 612: Time is 1193724881 seconds after epoch
2007-10-30 07:14:41 (00099805): Thread 612: Setting time header
2007-10-30 07:14:41 (00099805): Added *Date=Tue, 30 Oct 2007 06:14:41 GMT*
2007-10-30 07:14:41 (00099805): Added *Connection=close*
2007-10-30 07:14:41 (00099805): Added *Server=mt-daapd/svn-1695*
2007-10-30 07:14:41 (00099805): Added *Content-Type=text/html*
2007-10-30 07:14:41 (00099805): Added *Content-Language=en_us*
2007-10-30 07:14:41 (00099805): Thread 612: Using non-default handler
2007-10-30 07:14:41 (00099805): in main_auth
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Dispatching auth for /rsp/db/0 to plugin
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:41 (00099805): Checking if pw required for /rsp/db/0 as user
2007-10-30 07:14:41 (00099805): Nope
2007-10-30 07:14:41 (00099805): in main_handler
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Dispatching /rsp/db/0 to plugin
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Checking url /rsp/db/0
2007-10-30 07:14:41 (00099805): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:41 (00099805): Getting uri…
2007-10-30 07:14:41 (00099805): Mallocing privinfo…
2007-10-30 07:14:41 (00099805): Tokenizing url
2007-10-30 07:14:41 (00099805): Found 5 elements
2007-10-30 07:14:41 (00099805): Checking reponse 0
2007-10-30 07:14:41 (00099805): Checking reponse 1
2007-10-30 07:14:41 (00099805): Checking reponse 2
2007-10-30 07:14:41 (00099805): Found it! Index: 2
2007-10-30 07:14:41 (00099805): Returning token 2002
2007-10-30 07:14:41 (00099805): Entering sp_parse_phrase
2007-10-30 07:14:41 (00099805): Entering sp_parse_oexpr
2007-10-30 07:14:41 (00099805): Entering sp_parse_aexpr
2007-10-30 07:14:41 (00099805): Entering sp_parse_expr
2007-10-30 07:14:41 (00099805): Entering sp_parse_criterion
2007-10-30 07:14:41 (00099805): Entering sp_parse_int_criterion
2007-10-30 07:14:41 (00099805): Returning token 000f
2007-10-30 07:14:41 (00099805): Returning token 00fd
2007-10-30 07:14:41 (00099805): Exiting sp_parse_int_criterion (success)
2007-10-30 07:14:41 (00099805): Exiting sp_parse_criterion (success)
2007-10-30 07:14:41 (00099805): Exiting sp_parse_expr (success)
2007-10-30 07:14:41 (00099805): Exiting sp_parse_aexpr (success)
2007-10-30 07:14:41 (00099805): Exiting sp_parse_oexpr (success)
2007-10-30 07:14:41 (00099805): Exiting sp_parse_phrase (success)
2007-10-30 07:14:51 (00000402): Got listen socket 25
2007-10-30 07:14:51 (00099c06): Thread 613:
2007-10-30 07:14:51 (00099c06): Request: GET /rsp/db/0?query=id%3D796&type=full HTTP/1.0
2007-10-30 07:14:51 (00099c06): Thread 613: Read: User-Agent: Roku SoundBridge/3.0
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:51 (00099c06): Added *User-Agent=Roku SoundBridge/3.0*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: Host: 192.168.1.77:3689
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *Host=192.168.1.77:3689*
2007-10-30 07:14:51 (00099c06): Added *Host=192.168.1.77:3689*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: Accept: */*
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *Accept=*/**
2007-10-30 07:14:51 (00099c06): Added *Accept=*/**
2007-10-30 07:14:51 (00099c06): Thread 613: Read: Pragma: no-cache
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *Pragma=no-cache*
2007-10-30 07:14:51 (00099c06): Added *Pragma=no-cache*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: accept-encoding: gzip
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *accept-encoding=gzip*
2007-10-30 07:14:51 (00099c06): Added *accept-encoding=gzip*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: accept-codecs: wma,mpeg,wav,mp4a,alac
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:51 (00099c06): Added *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: rsp-version: 0.1
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *rsp-version=0.1*
2007-10-30 07:14:51 (00099c06): Added *rsp-version=0.1*
2007-10-30 07:14:51 (00099c06): Thread 613: Read: transcode-codecs: wav,mp3
2007-10-30 07:14:51 (00099c06): Thread 613: Adding header *transcode-codecs=wav,mp3*
2007-10-30 07:14:51 (00099c06): Added *transcode-codecs=wav,mp3*
2007-10-30 07:14:51 (00099c06): Thread 613: Read:
2007-10-30 07:14:51 (00099c06): Thread 613: Headers parsed!
2007-10-30 07:14:51 (00099c06): Checking to see if connection matches keep-alive
2007-10-30 07:14:51 (00099c06): Nope!
2007-10-30 07:14:51 (00099c06): Thread 613: Connection type HTTP/1.0
: Connection: non-persist
2007-10-30 07:14:51 (00099c06): Thread 613: parsing GET args
2007-10-30 07:14:51 (00099c06): Thread 613: Adding arg query = id=796
2007-10-30 07:14:51 (00099c06): Added *query=id=796*
2007-10-30 07:14:51 (00099c06): Thread 613: Adding arg type = full
2007-10-30 07:14:51 (00099c06): Added *type=full*
2007-10-30 07:14:51 (00099c06): Thread 613: Done parsing GET/POST args!
2007-10-30 07:14:51 (00099c06): Thread 613: Original URI: /rsp/db/0
2007-10-30 07:14:51 (00099c06): Thread 613: Translated URI: /rsp/db/0
2007-10-30 07:14:51 (00099c06): Thread 613: Preparing to find handler
2007-10-30 07:14:51 (00099c06): Checking /rsp/db/0 against handler for /
2007-10-30 07:14:51 (00099c06): Thread 613: URI Match!
2007-10-30 07:14:51 (00099c06): Thread 613: Time is 1193724891 seconds after epoch
2007-10-30 07:14:51 (00099c06): Thread 613: Setting time header
2007-10-30 07:14:51 (00099c06): Added *Date=Tue, 30 Oct 2007 06:14:51 GMT*
2007-10-30 07:14:51 (00099c06): Added *Connection=close*
2007-10-30 07:14:51 (00099c06): Added *Server=mt-daapd/svn-1695*
2007-10-30 07:14:51 (00099c06): Added *Content-Type=text/html*
2007-10-30 07:14:51 (00099c06): Added *Content-Language=en_us*
2007-10-30 07:14:51 (00099c06): Thread 613: Using non-default handler
2007-10-30 07:14:51 (00099c06): in main_auth
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Dispatching auth for /rsp/db/0 to plugin
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:51 (00099c06): Checking if pw required for /rsp/db/0 as user
2007-10-30 07:14:51 (00099c06): Nope
2007-10-30 07:14:51 (00099c06): in main_handler
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Dispatching /rsp/db/0 to plugin
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Checking url /rsp/db/0
2007-10-30 07:14:51 (00099c06): Dispatching /rsp/db/0 to rsp/svn-1695
2007-10-30 07:14:51 (00099c06): Getting uri…
2007-10-30 07:14:51 (00099c06): Mallocing privinfo…
2007-10-30 07:14:51 (00099c06): Tokenizing url
2007-10-30 07:14:51 (00099c06): Found 5 elements
2007-10-30 07:14:51 (00099c06): Checking reponse 0
2007-10-30 07:14:51 (00099c06): Checking reponse 1
2007-10-30 07:14:51 (00099c06): Checking reponse 2
2007-10-30 07:14:51 (00099c06): Found it! Index: 2
2007-10-30 07:14:51 (00099c06): Returning token 2002
2007-10-30 07:14:51 (00099c06): Entering sp_parse_phrase
2007-10-30 07:14:51 (00099c06): Entering sp_parse_oexpr
2007-10-30 07:14:51 (00099c06): Entering sp_parse_aexpr
2007-10-30 07:14:51 (00099c06): Entering sp_parse_expr
2007-10-30 07:14:51 (00099c06): Entering sp_parse_criterion
2007-10-30 07:14:51 (00099c06): Entering sp_parse_int_criterion
2007-10-30 07:14:51 (00099c06): Returning token 000f
2007-10-30 07:14:51 (00099c06): Returning token 00fd
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_int_criterion (success)
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_criterion (success)
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_expr (success)
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_aexpr (success)
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_oexpr (success)
2007-10-30 07:14:51 (00099c06): Exiting sp_parse_phrase (success)
2007-10-30 07:15:02 (00099003): Query: update songs set play_count=play_count + 1, time_played=1193724869 where id=10113
2007-10-30 07:15:02 (00099003): Error: database is locked
2007-10-30 07:15:02 (00099003): Thread 610: Terminating
2007-10-30 07:15:02 (00099003): Thread 610: Freeing request headers
2007-10-30 07:15:02 (00099404): Got query/filter
2007-10-30 07:15:02 (00099404): Fetching sql statement size
2007-10-30 07:15:02 (00099404): Size: 47
2007-10-30 07:15:02 (00099404): Serialized to : (id = 796)
2007-10-30 07:15:02 (00099404): Executing: select count(songs.id) from songs where ((id = 796))
2007-10-30 07:15:03 (00099003): Thread 610: Freeing response headers
2007-10-30 07:15:03 (00099003): Thread 610: Freeing request vars
2007-10-30 07:15:03 (00099003): Thread 610: Closing fd
2007-10-30 07:15:03 (00099003): With thread 610 exiting, 3 are still running