cpd73 wrote:
> I've installed the debug version. However, for me, the issue is *very*
> infrequent. I saw it once this morning, but before I'd installed this
> version, and haven't seen since. I'll leave the logging on, and report
> if I see the issue occurring. Thanks for taking the time to look into
> this.
Just played an album on one of my speakers, and the issue occurred. I'm
not 100% sure about this, but I -think- LMS was reporting volume of 100%
when it wasn't? Can't be sure, as I saw the volume at 100% so reduced
it, but I -think- this made it louder?
Does any of the following help?
Code:
--------------------
[15:42:36.479334] mDNSsearchCallback:623 [0x3d2370]: keep missing renderer
(Dining-Room)
[15:42:36.479431] mDNSsearchCallback:623 [0x3d15d8]: keep missing renderer
(Living-Room)
[16:24:40.104250] slimproto_close:904 [0x37c4b0] slimproto stop for Living
Room
[16:24:40.123205] sighandler:1340 Please wait for clean exit!
[16:24:40.288651] stream_close:425 [0x37c4b0] close stream
[16:24:40.389458] slimproto_close:904 [0x37ed78] slimproto stop for Dining
Room
[16:24:40.497132] stream_close:425 [0x37ed78] close stream
[16:24:40.612956] DelRaopDevice:960 [0x3d15d8]: Raop device stopped
[16:24:40.613149] DelRaopDevice:960 [0x3d2370]: Raop device stopped
[16:24:47.798499] main:1522 Starting squeeze2raop version: v0.4.21.2-dev-1
(Sep 13 2019 @ 15:00:50)
[16:24:47.798630] register_pcm:245 using pcm to decode aif,pcm
[16:24:47.798650] register_mad:424 using mad to decode mp3
[16:24:47.798665] register_alac:535 using alac to decode alc
[16:24:47.798679] register_flac:283 using flac to decode flc
[16:24:47.798693] register_faad:635 using faad to decode aac
[16:24:47.798706] register_vorbis:339 using vorbis to decode ogg
[16:24:47.798719] register_opus:282 using opus to decode ops
[16:24:47.798732] register_soxr:391 using soxr for resampling
[16:24:47.799349] Start:1272 Binding to 192.168.1.25
[16:24:47.799561] StartActiveRemote:1170 DACP port: 43089
[16:24:48.470775] AddRaopDevice:866 [0x3d15d8]: adding renderer (Dining-Room)
[16:24:48.471649] raopcl_create:728 [0xb6608950]: using ALAC coding
[16:24:48.477231] output_raop_thread_init:126 [0x37c4b0]: init output raop
[16:24:48.477271] output_init_common:293 allocating 1764000
[16:24:48.477427] resample_init:342 [0x37c4b0]: resampling sync recipe: 0x00,
flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000,
stopband_begin: 0.00000, phase_response: -1.0
[16:24:48.487758] slimproto:832 squeezelite [0x37c4b0] <=> player [0x3d15d8]
[16:24:48.487957] slimproto:833 [0x37c4b0] connecting to 192.168.1.25:3483
[16:24:48.488115] slimproto:863 [0x37c4b0] connected
[16:24:48.488142] sendHELO:116 [0x37c4b0] cap:
CanHTTPS=1,Model=squeezelite,ModelName=SqueezeLite,AccuratePlayPoints=1,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,flc,alc,aif,pcm,mp3
[16:24:48.576157] PlayerThread:558 [0x3d15d8]: processing volume: 35 (-19.59)
[16:24:48.741200] AddRaopDevice:866 [0x3d2370]: adding renderer (Living-Room)
[16:24:48.742055] raopcl_create:728 [0xb66134c0]: using ALAC coding
[16:24:48.747542] output_raop_thread_init:126 [0x37ed78]: init output raop
[16:24:48.747598] output_init_common:293 allocating 1764000
[16:24:48.747774] resample_init:342 [0x37ed78]: resampling sync recipe: 0x00,
flags: 0x00, scale: 0.89, precision: 0.0, passband_end: 0.00000,
stopband_begin: 0.00000, phase_response: -1.0
[16:24:48.751291] slimproto:832 squeezelite [0x37ed78] <=> player [0x3d2370]
[16:24:48.751410] slimproto:833 [0x37ed78] connecting to 192.168.1.25:3483
[16:24:48.751565] slimproto:863 [0x37ed78] connected
[16:24:48.751592] sendHELO:116 [0x37ed78] cap:
CanHTTPS=1,Model=squeezelite,ModelName=SqueezeLite,AccuratePlayPoints=1,HasDigitalOut=1,MaxSampleRate=96000,aac,ogg,flc,alc,aif,pcm,mp3
[16:24:48.761882] PlayerThread:558 [0x3d2370]: processing volume: 35 (-19.59)
[16:27:24.965878] PlayerThread:543 [0x3d15d8]: flushing ...
[16:27:25.330430] PlayerThread:413 [0x3d15d8]: Disconnecting 2532992146
[16:27:25.330484] PlayerThread:558 [0x3d15d8]: processing volume: 35 (-19.59)
[16:27:25.334546] process_strm:237 [0x37c4b0] strm command s
[16:27:25.334607] process_strm:312 [0x37c4b0], strm s autostart: 1 transition
period: 10 transition type: 0 codec: f
[16:27:25.334768] process_strm:345 [0x37c4b0]: codec:f, ch:255, s:255, r:255
[16:27:25.334825] stream_sock:485 [0x37c4b0] connecting to 192.168.1.25:9000
[16:27:25.334829] PlayerThread:516 [0x3d15d8]: raop connecting ...
[16:27:25.334963] stream_sock:561 [0x37c4b0] header: GET
/stream.mp3?player=aa:aa:b5:38:e2:d7 HTTP/1.0
[16:27:25.414712] stream_thread:261 [0x37c4b0] headers: len: 118
HTTP/1.1 200 OK
Server: Logitech Media Server (7.9.2 - 1562753347)
Connection: close
Content-Type: audio/x-flac
[16:27:25.432222] raopcl_connect:1006 [0xb6608950]: local interface
192.168.1.25
[16:27:25.511161] write_cb:115 [0x37c4b0]: setting track_start
[16:27:25.511224] resample_newstream:201 [0x37c4b0]: resampling from 48000 ->
44100
[16:27:25.511303] process_newstream:121 [0x37c4b0]: processing: active
[16:27:25.735144] exec_request:698 [0xb660bca8]: Body data 1076, B`
Ã5×ÄYáÕ:
îéC2Ißk}G.þFæÎ:
[16:27:25.796431] raopcl_accept_frames:429 [0xb6608950]: begining to stream
hts:0 n:3777809245.3420632213
[16:27:25.796550] raopcl_accept_frames:437 [0xb6608950]: restarting w/o pause
n:3777809245.3420632213, hts:166601387739622
[16:27:25.803307] PlayerThread:527 [0x3d15d8]: raop connected
[16:27:25.803365] PlayerThread:558 [0x3d15d8]: processing volume: 35 (-19.59)
[16:27:25.806654] _output_frames:53 [0x37c4b0]: start buffer frames: 389490
[16:27:25.806721] _output_frames:100 [0x37c4b0]: track start sample rate:
44100 replay_gain: 0
[16:27:25.817006] output_raop_thread:99 [0x37c4b0]: track actual start
time:2532995862 (gap:-1761971434)
[16:27:26.767624] raopcl_send_chunk:606 [0xb6608950]: check n:2532993583
p:2532996820 ts:166601387782214 sn:17888
retr: 0, avail: 0, send: 0, select: 0)
[16:27:26.811569] PlayerThread:505 [0x3d15d8]: volume repeat or trigger
timeout 35
[16:27:27.899920] ActiveRemoteThread:1042 [0x3d15d8]: remote command
setproperty?dmcp.device-volume=0.000000
[16:27:27.900039] ActiveRemoteThread:1111 [0x3d15d8]: volume feedback 100
(0.00)
[16:27:27.900354] cli_open_socket:185 [0x37c4b0]: opened CLI socket 19
[16:27:27.905927] sq_callback:348 [0x3d15d8]: volume ignored 100
[16:27:27.906121] sq_callback:348 [0x3d15d8]: volume ignored 100
[16:27:36.770890] raopcl_send_chunk:606 [0xb6608950]: check n:2533003586
p:2533006821 ts:166601388223270 sn:19141
retr: 0, avail: 0, send: 0, select: 0)
[16:27:38.001923] slimproto_run:609 [0x37c4b0] Closing CLI socket 19
[16:27:45.949879] PlayerThread:558 [0x3d15d8]: processing volume: 95 (-1.50)
[16:27:45.958557] PlayerThread:558 [0x3d15d8]: processing volume: 95 (-1.50)
[16:27:46.126820] PlayerThread:558 [0x3d15d8]: processing volume: 90 (-3.00)
[16:27:46.143451] PlayerThread:558 [0x3d15d8]: processing volume: 90 (-3.00)
[16:27:46.302214] PlayerThread:558 [0x3d15d8]: processing volume: 85 (-4.50)
[16:27:46.307603] PlayerThread:558 [0x3d15d8]: processing volume: 85 (-4.50)
[16:27:46.770719] raopcl_send_chunk:606 [0xb6608950]: check n:2533013586
p:2533016822 ts:166601388664326 sn:20394
retr: 0, avail: 0, send: 0, select: 0)
[16:27:47.736840] PlayerThread:558 [0x3d15d8]: processing volume: 80 (-6.00)
[16:27:47.744258] PlayerThread:558 [0x3d15d8]: processing volume: 80 (-6.00)
[16:27:47.917750] PlayerThread:558 [0x3d15d8]: processing volume: 75 (-7.50)
[16:27:47.922415] PlayerThread:558 [0x3d15d8]: processing volume: 75 (-7.50)
[16:27:48.144246] PlayerThread:558 [0x3d15d8]: processing volume: 70 (-9.00)
[16:27:48.149712] PlayerThread:558 [0x3d15d8]: processing volume: 70 (-9.00)
[16:27:48.322698] PlayerThread:558 [0x3d15d8]: processing volume: 65 (-10.50)
[16:27:48.327998] PlayerThread:558 [0x3d15d8]: processing volume: 65 (-10.50)
[16:27:48.491718] PlayerThread:558 [0x3d15d8]: processing volume: 60 (-12.00)
[16:27:48.501481] PlayerThread:558 [0x3d15d8]: processing volume: 60 (-12.00)
[16:27:48.674237] PlayerThread:558 [0x3d15d8]: processing volume: 55 (-13.50)
[16:27:48.682678] PlayerThread:558 [0x3d15d8]: processing volume: 55 (-13.50)
[16:27:48.847324] PlayerThread:558 [0x3d15d8]: processing volume: 50 (-15.00)
[16:27:48.859118] PlayerThread:558 [0x3d15d8]: processing volume: 50 (-15.00)
[16:27:49.032221] PlayerThread:558 [0x3d15d8]: processing volume: 45 (-16.53)
[16:27:49.041214] PlayerThread:558 [0x3d15d8]: processing volume: 45 (-16.53)
[16:27:49.210458] PlayerThread:558 [0x3d15d8]: processing volume: 40 (-18.06)
[16:27:49.222434] PlayerThread:558 [0x3d15d8]: processing volume: 40 (-18.06)
[16:27:49.390627] PlayerThread:558 [0x3d15d8]: processing volume: 35 (-19.59)
[16:27:49.397677] PlayerThread:558 [0x3d15d8]: processing volume: 35 (-19.59)
[16:27:49.572276] PlayerThread:558 [0x3d15d8]: processing volume: 30 (-21.12)
[16:27:49.583370] PlayerThread:558 [0x3d15d8]: processing volume: 30 (-21.12)
[16:27:49.778705] PlayerThread:558 [0x3d15d8]: processing volume: 25 (-22.65)
[16:27:49.785458] PlayerThread:558 [0x3d15d8]: processing volume: 25 (-22.65)
[16:27:49.972237] PlayerThread:558 [0x3d15d8]: processing volume: 20 (-24.18)
[16:27:49.980216] PlayerThread:558 [0x3d15d8]: processing volume: 20 (-24.18)
[16:27:54.160905] process_flush:170 [0x37c4b0]: process flush
[16:27:54.162420] PlayerThread:543 [0x3d15d8]: flushing ...
[16:27:54.162519] raopcl_flush:1104 [0xb6608950]: flushing up to s:21319
ts:384970411220454
[16:27:55.167950] PlayerThread:413 [0x3d15d8]: Disconnecting 2533021983
--------------------
------------------------------------------------------------------------
cpd73's Profile: http://forums.slimdevices.com/member.php?userid=66686
View this thread: http://forums.slimdevices.com/showthread.php?t=105198
_______________________________________________
plugins mailing list
[email protected]
http://lists.slimdevices.com/mailman/listinfo/plugins
