Newbie question about using firefly on Maxtor mss+

[rpedde]

@RickV wrote:

What do you mean with crank the log up to 9?

There is a debuglevel in the config page that you can set how verbose the logs are. If you set it to 9, then you get lots of info.

Double check you don’t have a password set while you are in the config pages.

Oh.. does iTunes work as a client?

Up till now I didn’t use iTunes. Before I used WMC 1.0 and WMC 2.0 on my windows-XP-PC and that was working. Is that enough information or should I really try to install iTunes on my PC?

It might help some — you should be able to connect to the server using iTunes as well. If iTunes worked but the sb didn’t, that would be a datapoint we could get some info from.

– Ron

[RickV]

Hi Ron,

Now I know what confused me. I didn’t see the possibility to show the advanced config on the configuration page. I think that displaying that as a button in stead of just a text-string the chance of overlooking will become less.

So I set the debug option to 9.

From the moment I executed the browse/artist on the Roku up till the failed-browse the log-file looks as below:

2006-12-07 13:44:57 (00000400): SocketDataReady got a packet from 192.168.2.102 to 224.0.0.251 on interface 192.168.2.47/eth0/2

2006-12-07 13:44:57 (00000400): Received Query from 192.168.2.102  :5353 to 224.0.0.251    :5353 on 0x555E2150 with  2 Questions,  0 Answers,  0 Authorities,  0 Additionals

2006-12-07 13:44:57 (00000400): AddRecordToResponseList: firefly svn-1433 on (none)._rsp._tcp.local. (SRV) already in list

2006-12-07 13:44:57 (00000400): AddRecordToResponseList: firefly svn-1433 on (none)._rsp._tcp.local. (TXT) already in list

2006-12-07 13:44:57 (00000400): AddRecordToResponseList: (none).local. (Addr) already in list

2006-12-07 13:44:57 (00000400): Unicast Response: 0 Questions, 2 Answers, 1 Additional to 192.168.2.102  :5353 on 555E2150/4

2006-12-07 13:44:57 (00000400): mDNS_Execute

2006-12-07 13:44:57 (00000400): SendResponses: Sending 0 Deregistrations, 0 Announcements, 3 Answers, 0 Additionals on 555E2150

2006-12-07 13:44:57 (00000400): SendResponses: Next in 2013265920 ticks

2006-12-07 13:44:57 (00000400): select(6, 0.097656)

2006-12-07 13:44:57 (00000400): SocketDataReady got a packet from 192.168.2.47 to 224.0.0.251 on interface 192.168.2.47/eth0/2

2006-12-07 13:44:57 (00000400): Received Response from 192.168.2.47    addressed to 224.0.0.251     on 555E2150 TTL 255 with  0 Questions,  3 Answers,  0 Authorities,  0 Additionals

2006-12-07 13:44:57 (00000400): select(6, 0.073242)

2006-12-07 13:44:57 (00000400): SocketDataReady got a packet from 192.168.2.102 to 224.0.0.251 on interface 192.168.2.47/eth0/2

2006-12-07 13:44:57 (00000400): Received Query from 192.168.2.102  :5353 to 224.0.0.251    :5353 on 0x555E2150 with  1 Question,   1 Answer,   0 Authorities,  0 Additionals

2006-12-07 13:44:57 (00000400): AddRecordToResponseList: (none).local. (Addr) already in list

2006-12-07 13:44:57 (00000400): select(6, 0.071289)

2006-12-07 13:44:57 (00000c04): Thread 0: Entering ws_dispatcher (Connection from 192.168.2.102)

2006-12-07 13:44:57 (00000c04): Thread 0: got request

2006-12-07 13:44:57 (00000c04): Request: GET /rsp/info HTTP/1.0

2006-12-07 13:44:57 (00000c04): Thread 0: Read: User-Agent: Roku SoundBridge/2.5

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *User-Agent=Roku SoundBridge/2.5*

2006-12-07 13:44:57 (00000c04): Added *User-Agent=Roku SoundBridge/2.5*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: Host: 192.168.2.47:3689

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *Host=192.168.2.47:3689*

2006-12-07 13:44:57 (00000c04): Added *Host=192.168.2.47:3689*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: Accept: */*

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *Accept=*/**

2006-12-07 13:44:57 (00000c04): Added *Accept=*/**

2006-12-07 13:44:57 (00000c04): Thread 0: Read: Pragma: no-cache

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *Pragma=no-cache*

2006-12-07 13:44:57 (00000c04): Added *Pragma=no-cache*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: accept-encoding: gzip

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *accept-encoding=gzip*

2006-12-07 13:44:57 (00000c04): Added *accept-encoding=gzip*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: accept-codecs: wma,mpeg,wav,mp4a

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *accept-codecs=wma,mpeg,wav,mp4a*

2006-12-07 13:44:57 (00000c04): Added *accept-codecs=wma,mpeg,wav,mp4a*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: rsp-version: 0.1

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *rsp-version=0.1*

2006-12-07 13:44:57 (00000c04): Added *rsp-version=0.1*

2006-12-07 13:44:57 (00000c04): Thread 0: Read: transcode-codecs: wav,mp3

2006-12-07 13:44:57 (00000c04): Thread 0: Adding header *transcode-codecs=wav,mp3*

2006-12-07 13:44:57 (00000c04): Added *transcode-codecs=wav,mp3*

2006-12-07 13:44:57 (00000c04): Thread 0: Read:

2006-12-07 13:44:57 (00000c04): Thread 0: Headers parsed!

2006-12-07 13:44:57 (00000c04): Checking to see if connection matches keep-alive

2006-12-07 13:44:57 (00000c04): Nope!

2006-12-07 13:44:57 (00000c04): Thread 0: Connection type HTTP/1.0

: Connection: non-persist

2006-12-07 13:44:57 (00000c04): Thread 0: Original URI: /rsp/info

2006-12-07 13:44:57 (00000c04): Thread 0: Translated URI: /rsp/info

2006-12-07 13:44:57 (00000c04): Thread 0: Preparing to find handler

2006-12-07 13:44:57 (00000400): mDNS_Execute

2006-12-07 13:44:57 (00000400): select(6, 1964767.003906)

2006-12-07 13:44:57 (00000c04): Thread 0: URI Match!

2006-12-07 13:44:57 (00000c04): Thread 0: Time is 1165527897 seconds after epoch

2006-12-07 13:44:57 (00000c04): Thread 0: Setting time header

2006-12-07 13:44:57 (00000c04): Added *Date=Thu, 7 Dec 2006 21:44:57 GMT*

2006-12-07 13:44:57 (00000c04): Added *Connection=close*

2006-12-07 13:44:57 (00000c04): Added *Server=mt-daapd/svn-1433*

2006-12-07 13:44:57 (00000c04): Added *Content-Type=text/html*

2006-12-07 13:44:57 (00000c04): Added *Content-Language=en_us*

2006-12-07 13:44:57 (00000c04): Thread 0: Using non-default handler

2006-12-07 13:44:57 (00000c04): in main_auth

2006-12-07 13:44:57 (00000c04): Checking url /rsp/info

2006-12-07 13:44:57 (00000c04): Dispatching auth for /rsp/info to plugin

2006-12-07 13:44:57 (00000c04): Checking url /rsp/info

2006-12-07 13:44:57 (00000c04): Dispatching /rsp/info to rsp/svn-1433

2006-12-07 13:44:57 (00000c04): in main_handler

2006-12-07 13:44:57 (00000c04): Checking url /rsp/info

2006-12-07 13:44:57 (00000c04): Dispatching /rsp/info to plugin

2006-12-07 13:44:57 (00000c04): Checking url /rsp/info

2006-12-07 13:44:57 (00000c04): Dispatching /rsp/info to rsp/svn-1433

2006-12-07 13:44:57 (00000c04): Getting uri...

2006-12-07 13:44:57 (00000c04): Mallocing privinfo...

2006-12-07 13:44:57 (00000c04): Tokenizing url

2006-12-07 13:44:57 (00000c04): Found 5 elements

2006-12-07 13:44:57 (00000c04): Checking reponse 0

2006-12-07 13:44:57 (00000c04): Found it! Index: 0

2006-12-07 13:44:57 (00000c04): Starting rsp_info

2006-12-07 13:44:57 (00000c04): Added *Cache-Control=no-cache*

2006-12-07 13:44:57 (00000c04): Added *Expires=-1*

2006-12-07 13:44:57 (00000c04): Updating Content-Type from text/html to text/xml; charset=utf-8

2006-12-07 13:44:57 (00000c04): Emitting reponse header Expires: -1

2006-12-07 13:44:57 (00000c04): Emitting reponse header Cache-Control: no-cache

2006-12-07 13:44:57 (00000c04): Emitting reponse header Content-Language: en_us

2006-12-07 13:44:57 (00000c04): Emitting reponse header Content-Type: text/xml; charset=utf-8

2006-12-07 13:44:57 (00000c04): Emitting reponse header Server: mt-daapd/svn-1433

2006-12-07 13:44:57 (00000c04): Emitting reponse header Connection: close

2006-12-07 13:44:57 (00000c04): Emitting reponse header Date: Thu, 7 Dec 2006 21:44:57 GMT

2006-12-07 13:44:57 (00000c04): Executing: select count(*) FROM songs

2006-12-07 13:45:01 (00000c04): Thread 0: Terminating

2006-12-07 13:45:01 (00000c04): Thread 0: Freeing request headers

2006-12-07 13:45:01 (00000c04): Thread 0: Freeing response headers

2006-12-07 13:45:01 (00000c04): Thread 0: Freeing request vars

2006-12-07 13:45:01 (00000c04): Thread 0: Closing fd

2006-12-07 13:45:01 (00000c04): With thread 0 exiting, 0 are still running

2006-12-07 13:45:05 (00001004): Thread 1: Entering ws_dispatcher (Connection from 192.168.2.102)

2006-12-07 13:45:05 (00001004): Thread 1: got request

2006-12-07 13:45:05 (00001004): Request: GET /rsp/db/0/artist?type=browse HTTP/1.0

2006-12-07 13:45:05 (00001004): Thread 1: Read: User-Agent: Roku SoundBridge/2.5

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *User-Agent=Roku SoundBridge/2.5*

2006-12-07 13:45:05 (00001004): Added *User-Agent=Roku SoundBridge/2.5*

2006-12-07 13:45:05 (00001004): Thread 1: Read: Host: 192.168.2.47:3689

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *Host=192.168.2.47:3689*

2006-12-07 13:45:05 (00001004): Added *Host=192.168.2.47:3689*

2006-12-07 13:45:05 (00001004): Thread 1: Read: Accept: */*

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *Accept=*/**

2006-12-07 13:45:05 (00001004): Added *Accept=*/**

2006-12-07 13:45:05 (00001004): Thread 1: Read: Pragma: no-cache

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *Pragma=no-cache*

2006-12-07 13:45:05 (00001004): Added *Pragma=no-cache*

2006-12-07 13:45:05 (00001004): Thread 1: Read: accept-encoding: gzip

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *accept-encoding=gzip*

2006-12-07 13:45:05 (00001004): Added *accept-encoding=gzip*

2006-12-07 13:45:05 (00001004): Thread 1: Read: accept-codecs: wma,mpeg,wav,mp4a

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *accept-codecs=wma,mpeg,wav,mp4a*

2006-12-07 13:45:05 (00001004): Added *accept-codecs=wma,mpeg,wav,mp4a*

2006-12-07 13:45:05 (00001004): Thread 1: Read: rsp-version: 0.1

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *rsp-version=0.1*

2006-12-07 13:45:05 (00001004): Added *rsp-version=0.1*

2006-12-07 13:45:05 (00001004): Thread 1: Read: transcode-codecs: wav,mp3

2006-12-07 13:45:05 (00001004): Thread 1: Adding header *transcode-codecs=wav,mp3*

2006-12-07 13:45:05 (00001004): Added *transcode-codecs=wav,mp3*

2006-12-07 13:45:05 (00001004): Thread 1: Read:

2006-12-07 13:45:05 (00001004): Thread 1: Headers parsed!

2006-12-07 13:45:05 (00001004): Checking to see if connection matches keep-alive

2006-12-07 13:45:05 (00001004): Nope!

2006-12-07 13:45:05 (00001004): Thread 1: Connection type HTTP/1.0: Connection: non-persist

2006-12-07 13:45:05 (00001004): Thread 1: parsing GET args

2006-12-07 13:45:05 (00001004): Thread 1: Entering ws_getgetvars (type=browse)

2006-12-07 13:45:05 (00001004): Thread 1: Adding arg type = browse

2006-12-07 13:45:05 (00001004): Added *type=browse*

2006-12-07 13:45:05 (00001004): Thread 1: Done parsing GET/POST args!

2006-12-07 13:45:05 (00001004): Thread 1: Original URI: /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Thread 1: Translated URI: /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Thread 1: Preparing to find handler

2006-12-07 13:45:05 (00001004): Thread 1: URI Match!

2006-12-07 13:45:05 (00001004): Thread 1: Time is 1165527905 seconds after epoch

2006-12-07 13:45:05 (00001004): Thread 1: Setting time header

2006-12-07 13:45:05 (00001004): Added *Date=Thu, 7 Dec 2006 21:45:05 GMT*

2006-12-07 13:45:05 (00001004): Added *Connection=close*

2006-12-07 13:45:05 (00001004): Added *Server=mt-daapd/svn-1433*

2006-12-07 13:45:05 (00001004): Added *Content-Type=text/html*

2006-12-07 13:45:05 (00001004): Added *Content-Language=en_us*

2006-12-07 13:45:05 (00001004): Thread 1: Using non-default handler

2006-12-07 13:45:05 (00001004): in main_auth

2006-12-07 13:45:05 (00001004): Checking url /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Dispatching auth for /rsp/db/0/artist to plugin

2006-12-07 13:45:05 (00001004): Checking url /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Dispatching /rsp/db/0/artist to rsp/svn-1433

2006-12-07 13:45:05 (00001004): in main_handler

2006-12-07 13:45:05 (00001004): Checking url /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Dispatching /rsp/db/0/artist to plugin

2006-12-07 13:45:05 (00001004): Checking url /rsp/db/0/artist

2006-12-07 13:45:05 (00001004): Dispatching /rsp/db/0/artist to rsp/svn-1433

2006-12-07 13:45:05 (00001004): Getting uri...

2006-12-07 13:45:05 (00001004): Mallocing privinfo...

2006-12-07 13:45:05 (00001004): Tokenizing url

2006-12-07 13:45:05 (00001004): Found 5 elements

2006-12-07 13:45:05 (00001004): Checking reponse 0

2006-12-07 13:45:05 (00001004): Checking reponse 1

2006-12-07 13:45:05 (00001004): Checking reponse 2

2006-12-07 13:45:05 (00001004): Checking reponse 3

2006-12-07 13:45:05 (00001004): Found it! Index: 3

2006-12-07 13:45:05 (00001004): No query/filter

2006-12-07 13:45:05 (00001004): Executing: select count(artist) from (select distinct artist from songs  where (data_kind = 0)  and (artist !=''))

2006-12-07 13:45:20 (00001004): Number of results: 6158

2006-12-07 13:45:20 (00001004): Executing: select distinct artist from songs  where (data_kind = 0)  and (artist !='')

2006-12-07 13:45:20 (00001004): Added *Cache-Control=no-cache*

2006-12-07 13:45:20 (00001004): Added *Expires=-1*

2006-12-07 13:45:20 (00001004): Updating Content-Type from text/html to text/xml; charset=utf-8

2006-12-07 13:45:20 (00001004): Emitting reponse header Expires: -1

2006-12-07 13:45:20 (00001004): Emitting reponse header Cache-Control: no-cache

2006-12-07 13:45:20 (00001004): Emitting reponse header Content-Language: en_us

2006-12-07 13:45:20 (00001004): Emitting reponse header Content-Type: text/xml; charset=utf-8

2006-12-07 13:45:20 (00001004): Emitting reponse header Server: mt-daapd/svn-1433

2006-12-07 13:45:20 (00001004): Emitting reponse header Connection: close

2006-12-07 13:45:20 (00001004): Emitting reponse header Date: Thu, 7 Dec 2006 21:45:05 GMT

2006-12-07 13:45:35 (00001004): Thread 1: Terminating

2006-12-07 13:45:35 (00001004): Thread 1: Freeing request headers

2006-12-07 13:45:35 (00001004): Thread 1: Freeing response headers

2006-12-07 13:45:35 (00001004): Thread 1: Freeing request vars

2006-12-07 13:45:35 (00001004): Thread 1: Closing fd

2006-12-07 13:45:35 (00001004): With thread 1 exiting, 0 are still running

2006-12-07 13:45:50 (00000400): Skipped bground scan... no users

It looks like there is a problem selecting the 6158 distinct artists. Is my music-library to big?
Also I see that the time on the MSS is not in sync with the Roku…

Looking forward to your answer.

[rpedde]

@RickV wrote:

It looks like there is a problem selecting the 6158 distinct artists. Is my music-library to big?
Also I see that the time on the MSS is not in sync with the Roku…

Looking forward to your answer.

No, that should probably be fine. I don’t see any obvious errors. Unless there is some kind of badly formed xml returned or something.

Is it possible you could gzip your songs.db and email it to me at [email protected]?

[RickV]

Hi Ron,

I will try to deliver that for you. I have installed gzip via “ipkg install gzip”. How do I start it? Is it possible to split the zip-file in smaller parts via gzip so I can mail it?

[Iris]

Even though the time setting via the MSS web interface and that viewed via explorer is correct the one recorded in Firefly’s log is 2 hours behind. Is there another location that the time should be set for Firefly? My location is 6 hours behind UTC and DST is off.

Iris

[rpedde]

@Iris wrote:

Even though the time setting via the MSS web interface and that viewed via explorer is correct the one recorded in Firefly’s log is 2 hours behind. Is there another location that the time should be set for Firefly? My location is 6 hours behind UTC and DST is off.

Iris

Nope, it gets the time from the system, so that must be the time on the machine. You can check by doing a “date” from the console.

[Iris]

@rpedde wrote:

@Iris wrote:

Even though the time setting via the MSS web interface and that viewed via explorer is correct the one recorded in Firefly’s log is 2 hours behind. Is there another location that the time should be set for Firefly? My location is 6 hours behind UTC and DST is off.

Iris

Nope, it gets the time from the system, so that must be the time on the machine. You can check by doing a “date” from the console.

Well, not that this is directly related to Firefly but I would of thought that the time/date shown on the MSS web interface was the date stored on that machine but I’m getting conflicting results. I read somewhere on a Wiki about setting this so I’ll have to try and find that again. Hmmmm…

Iris

[Iris]

I’ve tried a modified version of what was posted on the FAQ for the MSS – that being:

# nvram set time_zone=CST+6CST
# nvram commit
# ntpclient -h 192.43.244.18 -s
# hwclock -w
# reboot

and it seems to be working after a few tries getting it right. I initially relied upon the MSS web interface but I guess I can’t rely on it 100%. In fact, in my searches I did find a post that mentioned the time set feature of the web interface had a bug in it but a version wasn’t mentioned.

[RickV]

Hi Ron,

I tested with a smaller music-folder. Just one “CD” in it and then it works well. So it looks like there is a limitation on the number of songs in a music-folder.

In the advanced settings I see that it’s possible to select sqlite3 as database type. Do you think it’s possible that version can handle bigger music folders?

Greetings,
Rick
PS: Did you receive the compressed songs-database?

[rpedde]

@RickV wrote:

Hi Ron,

I tested with a smaller music-folder. Just one “CD” in it and then it works well. So it looks like there is a limitation on the number of songs in a music-folder.

In the advanced settings I see that it’s possible to select sqlite3 as database type. Do you think it’s possible that version can handle bigger music folders?

Greetings,
Rick
PS: Did you receive the compressed songs-database?

I don’t believe it’s a limit on number of songs. There are folks that are running over 100K songs. I did get the database, but haven’t loaded it up yet. I hope to look at it later tonight.

— Ron

[Author]

Posts