AirConnect - audio stopping problem

Audio stops on Sonos at 8:06:06

# Audio stops on Sonos at 8:06:06

# macOS: 10.11.6 (El Capitan)
# iTunes: 12.7.3.46
# Sonos: up-to-date firmware as of 2/15/2018
# AirConnect: 5faf953 0.2.0.3
# Command: bin/airupnp-osx-multi -l 1500:3000

imac:AirConnect millers$ bin/airupnp-osx-multi -l 1500:3000
[07:51:33.113278] main:1191 Starting airupnp version: v0.2.0.3 (Feb  4 2018 @ 20:36:41)
[07:51:33.114050] main:1199 no config file, using defaults
[07:51:33.114858] Start:951 Binding to 10.0.0.54:49152
[07:51:38.678603] AddMRDevice:867 [0x100079510]: adding renderer (Kitchen)
[07:51:38.709192] MasterHandler:581 [0x100079510]: subscribe success
[07:51:38.737598] ProcessEvent:386 [0x100079510]: UPnP Volume local change 28
[07:52:12.087795] rtsp_thread:296 got RTSP connection 11
[07:52:12.088927] handle_rtsp:344 [0x1001110f0]: challenge kairtftbAiApig/sB5rdDQ
[07:52:12.094069] rtsp_thread:311 RTSP close 11
[07:52:18.303340] rtsp_thread:296 got RTSP connection 11
[07:52:18.304532] handle_rtsp:337 [0x1001110f0]: received ANNOUNCE
[07:52:18.309018] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 1


[07:52:18.310313] handle_rtsp:337 [0x1001110f0]: received SETUP
[07:52:18.310467] hairtunes_init:275 [0x101813a00]: Using FLAC
[07:52:18.310695] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Transport: RTP/AVP/UDP;unicast;mode=record;control_port=50927;timing_port=65169;server_port=51065
Session: DEADBEEF
Audio-Jack-Status: connected; type=analog
CSeq: 2


[07:52:18.312091] handle_rtsp:337 [0x1001110f0]: received RECORD
[07:52:18.312114] callback:270 [0x100079510]: Stream
[07:52:18.312247] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Latency: 66150
Audio-Jack-Status: connected; type=analog
CSeq: 3


[07:52:18.313679] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:18.313702] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.762618
[07:52:18.313712] CtrlSetVolume:252 [0x100079510]: uPNP volume 60 (cookie 0x0)
[07:52:18.313813] callback:320 [0x100079510]: Volume[0..100] 60
[07:52:18.313846] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 4


[07:52:18.315943] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:18.315970] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.762618
[07:52:18.315979] CtrlSetVolume:252 [0x100079510]: uPNP volume 60 (cookie 0x1)
[07:52:18.320293] callback:320 [0x100079510]: Volume[0..100] 60
[07:52:18.320403] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 5


[07:52:18.376451] search_remote_cb:546 [0x1001110f0]: found ActiveRemote for F51B82E2646D10C6 at 10.0.0.54:3689
[07:52:18.571694] rtp_thread_func:581 [0x101813a00]: 1st sync packet received
[07:52:18.571858] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[07:52:18.571939] buffer_put_packet:445 [0x101813a00]: fill status [level:65535] [W:64780 R:64781]
[07:52:18.572148] AVTSetURI:90 [0x100079510]: uPNP setURI http://10.0.0.54:64479/stream.flac (cookie 0x2)
[07:52:18.572369] AVTPlay:150 [0x100079510]: uPNP play (cookie 0x3)
[07:52:18.572401] CtrlSetVolume:252 [0x100079510]: uPNP volume 60 (cookie 0x3)
[07:52:18.573315] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:18.573346] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 6


[07:52:18.574967] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:18.575000] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 7


[07:52:18.576297] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:18.576331] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 8


[07:52:18.872687] http_thread_func:860 [0x101813a00]: got HTTP connection 17 (silent frames 375)
[07:52:18.879348] handle_http:996 [0x101813a00]: received GET
[07:52:18.879394] handle_http:1013 [0x101813a00]: responding:
HTTP/1.0 200 OK
Server: HairTunes
Content-Type: audio/flac
Connection: close


[07:52:18.884804] buffer_get_frame:780 [0x101813a00]: drain status [level:40] [W:64821 R:64781] [R:0 S:0]
[07:52:19.118524] ActionHandler:462 [0x100079510]: uPNP playing
[07:52:22.651551] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:65292 R:65292]
[07:52:22.667425] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:65294 R:65293] [R:0 S:0]
[07:52:23.087308] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.087338] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.460900
[07:52:23.087347] CtrlSetVolume:252 [0x100079510]: uPNP volume 61 (cookie 0x11)
[07:52:23.087451] callback:320 [0x100079510]: Volume[0..100] 61
[07:52:23.087576] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 9


[07:52:23.133350] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.133389] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.123877
[07:52:23.133397] CtrlSetVolume:252 [0x100079510]: uPNP volume 62 (cookie 0x14)
[07:52:23.133722] callback:320 [0x100079510]: Volume[0..100] 62
[07:52:23.133861] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 10


[07:52:23.179393] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.179425] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.873743
[07:52:23.179433] CtrlSetVolume:252 [0x100079510]: uPNP volume 63 (cookie 0x15)
[07:52:23.179539] callback:320 [0x100079510]: Volume[0..100] 63
[07:52:23.179703] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 11


[07:52:23.233473] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.233503] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.536482
[07:52:23.233510] CtrlSetVolume:252 [0x100079510]: uPNP volume 64 (cookie 0x16)
[07:52:23.233611] callback:320 [0x100079510]: Volume[0..100] 64
[07:52:23.233667] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 12


[07:52:23.267231] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.267258] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.199383
[07:52:23.267266] CtrlSetVolume:252 [0x100079510]: uPNP volume 66 (cookie 0x17)
[07:52:23.267371] callback:320 [0x100079510]: Volume[0..100] 66
[07:52:23.267475] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 13


[07:52:23.312442] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.312474] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -9.862494
[07:52:23.312483] CtrlSetVolume:252 [0x100079510]: uPNP volume 67 (cookie 0x18)
[07:52:23.312614] callback:320 [0x100079510]: Volume[0..100] 67
[07:52:23.312710] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 14


[07:52:23.342064] ProcessEvent:386 [0x100079510]: UPnP Volume local change 64
[07:52:23.344312] ActionHandler:476 Error in action callback -- 801 (cookie 0x17)
[07:52:23.739912] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.739958] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.199383
[07:52:23.739968] CtrlSetVolume:252 [0x100079510]: uPNP volume 66 (cookie 0x1a)
[07:52:23.740071] callback:320 [0x100079510]: Volume[0..100] 66
[07:52:23.740170] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 15


[07:52:23.796149] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.796178] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.536482
[07:52:23.796187] CtrlSetVolume:252 [0x100079510]: uPNP volume 64 (cookie 0x1b)
[07:52:23.796291] callback:320 [0x100079510]: Volume[0..100] 64
[07:52:23.796390] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 16


[07:52:23.829903] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.829932] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -10.873743
[07:52:23.829941] CtrlSetVolume:252 [0x100079510]: uPNP volume 63 (cookie 0x1c)
[07:52:23.830158] callback:320 [0x100079510]: Volume[0..100] 63
[07:52:23.831419] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 17


[07:52:23.908519] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.908554] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.210648
[07:52:23.908562] CtrlSetVolume:252 [0x100079510]: uPNP volume 62 (cookie 0x1d)
[07:52:23.908658] callback:320 [0x100079510]: Volume[0..100] 62
[07:52:23.908774] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 18


[07:52:23.929729] ProcessEvent:386 [0x100079510]: UPnP Volume local change 63
[07:52:23.988559] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:23.988595] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -11.710918
[07:52:23.988604] CtrlSetVolume:252 [0x100079510]: uPNP volume 60 (cookie 0x1e)
[07:52:23.988734] callback:320 [0x100079510]: Volume[0..100] 60
[07:52:23.988776] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 19


[07:52:24.066189] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:24.066217] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -12.048094
[07:52:24.066224] CtrlSetVolume:252 [0x100079510]: uPNP volume 59 (cookie 0x1f)
[07:52:24.066327] callback:320 [0x100079510]: Volume[0..100] 59
[07:52:24.066455] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 20


[07:52:24.189914] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:24.189945] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -12.385091
[07:52:24.189954] CtrlSetVolume:252 [0x100079510]: uPNP volume 58 (cookie 0x22)
[07:52:24.190057] callback:320 [0x100079510]: Volume[0..100] 58
[07:52:24.190197] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 21


[07:52:24.931442] ProcessEvent:386 [0x100079510]: UPnP Volume local change 66
[07:52:26.738462] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:268 R:268]
[07:52:26.748707] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:270 R:269] [R:0 S:0]
[07:52:30.534936] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.534968] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -13.146077
[07:52:30.534978] CtrlSetVolume:252 [0x100079510]: uPNP volume 56 (cookie 0x35)
[07:52:30.535082] callback:320 [0x100079510]: Volume[0..100] 56
[07:52:30.535214] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 22


[07:52:30.558494] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.558528] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -13.396497
[07:52:30.558537] CtrlSetVolume:252 [0x100079510]: uPNP volume 55 (cookie 0x36)
[07:52:30.558635] callback:320 [0x100079510]: Volume[0..100] 55
[07:52:30.558674] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 23


[07:52:30.575959] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.575990] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -13.897089
[07:52:30.575999] CtrlSetVolume:252 [0x100079510]: uPNP volume 53 (cookie 0x37)
[07:52:30.576103] callback:320 [0x100079510]: Volume[0..100] 53
[07:52:30.576138] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 24


[07:52:30.592113] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.592141] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.398350
[07:52:30.592149] CtrlSetVolume:252 [0x100079510]: uPNP volume 52 (cookie 0x38)
[07:52:30.592254] callback:320 [0x100079510]: Volume[0..100] 52
[07:52:30.592352] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 25


[07:52:30.609293] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.609321] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.899323
[07:52:30.609330] CtrlSetVolume:252 [0x100079510]: uPNP volume 50 (cookie 0x39)
[07:52:30.609431] callback:320 [0x100079510]: Volume[0..100] 50
[07:52:30.609458] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 26


[07:52:30.626925] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.626961] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.148939
[07:52:30.626970] CtrlSetVolume:252 [0x100079510]: uPNP volume 49 (cookie 0x3a)
[07:52:30.627075] callback:320 [0x100079510]: Volume[0..100] 49
[07:52:30.627190] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 27


[07:52:30.748749] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.748778] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.399695
[07:52:30.748787] CtrlSetVolume:252 [0x100079510]: uPNP volume 48 (cookie 0x3c)
[07:52:30.748889] callback:320 [0x100079510]: Volume[0..100] 48
[07:52:30.748999] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 28


[07:52:30.772283] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.772310] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.650674
[07:52:30.772317] CtrlSetVolume:252 [0x100079510]: uPNP volume 47 (cookie 0x3d)
[07:52:30.772418] callback:320 [0x100079510]: Volume[0..100] 47
[07:52:30.772527] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 29


[07:52:30.785319] ActionHandler:476 Error in action callback -- 801 (cookie 0x3c)
[07:52:30.791061] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.791092] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.151636
[07:52:30.791101] CtrlSetVolume:252 [0x100079510]: uPNP volume 46 (cookie 0x3e)
[07:52:30.791219] callback:320 [0x100079510]: Volume[0..100] 46
[07:52:30.791360] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 30


[07:52:30.807313] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.807341] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.401117
[07:52:30.807350] CtrlSetVolume:252 [0x100079510]: uPNP volume 45 (cookie 0x3f)
[07:52:30.807453] callback:320 [0x100079510]: Volume[0..100] 45
[07:52:30.807505] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 31


[07:52:30.824537] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:780 R:780]
[07:52:30.828542] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.828577] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.651663
[07:52:30.828585] CtrlSetVolume:252 [0x100079510]: uPNP volume 44 (cookie 0x40)
[07:52:30.828691] callback:320 [0x100079510]: Volume[0..100] 44
[07:52:30.828792] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 32


[07:52:30.838922] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:782 R:781] [R:0 S:0]
[07:52:30.906225] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:30.906253] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.988941
[07:52:30.906261] CtrlSetVolume:252 [0x100079510]: uPNP volume 43 (cookie 0x41)
[07:52:30.906370] callback:320 [0x100079510]: Volume[0..100] 43
[07:52:30.906493] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 33


[07:52:31.333894] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.333925] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.651663
[07:52:31.333934] CtrlSetVolume:252 [0x100079510]: uPNP volume 44 (cookie 0x44)
[07:52:31.334040] callback:320 [0x100079510]: Volume[0..100] 44
[07:52:31.334074] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 34


[07:52:31.370104] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.370170] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.314846
[07:52:31.370184] CtrlSetVolume:252 [0x100079510]: uPNP volume 45 (cookie 0x45)
[07:52:31.370317] callback:320 [0x100079510]: Volume[0..100] 45
[07:52:31.370523] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 35


[07:52:31.391541] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.391573] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.064426
[07:52:31.391582] CtrlSetVolume:252 [0x100079510]: uPNP volume 46 (cookie 0x46)
[07:52:31.391688] callback:320 [0x100079510]: Volume[0..100] 46
[07:52:31.391726] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 36


[07:52:31.413614] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.413645] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.814472
[07:52:31.413653] CtrlSetVolume:252 [0x100079510]: uPNP volume 47 (cookie 0x47)
[07:52:31.413757] callback:320 [0x100079510]: Volume[0..100] 47
[07:52:31.413852] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 37


[07:52:31.447358] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.447387] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.477301
[07:52:31.447396] CtrlSetVolume:252 [0x100079510]: uPNP volume 48 (cookie 0x48)
[07:52:31.447491] callback:320 [0x100079510]: Volume[0..100] 48
[07:52:31.447601] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 38


[07:52:31.481233] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.481260] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.139845
[07:52:31.481267] CtrlSetVolume:252 [0x100079510]: uPNP volume 49 (cookie 0x49)
[07:52:31.481367] callback:320 [0x100079510]: Volume[0..100] 49
[07:52:31.481478] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 39


[07:52:31.525024] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.525054] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.802617
[07:52:31.525063] CtrlSetVolume:252 [0x100079510]: uPNP volume 50 (cookie 0x4a)
[07:52:31.525164] callback:320 [0x100079510]: Volume[0..100] 50
[07:52:31.525260] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 40


[07:52:31.579254] ProcessEvent:386 [0x100079510]: UPnP Volume local change 49
[07:52:31.581142] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.581165] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.466070
[07:52:31.581173] CtrlSetVolume:252 [0x100079510]: uPNP volume 51 (cookie 0x4b)
[07:52:31.581273] callback:320 [0x100079510]: Volume[0..100] 51
[07:52:31.581364] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 41


[07:52:31.637487] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.637514] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.128585
[07:52:31.637523] CtrlSetVolume:252 [0x100079510]: uPNP volume 52 (cookie 0x4c)
[07:52:31.637625] callback:320 [0x100079510]: Volume[0..100] 52
[07:52:31.637731] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 42


[07:52:31.727545] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:31.727571] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -13.791393
[07:52:31.727579] CtrlSetVolume:252 [0x100079510]: uPNP volume 54 (cookie 0x4e)
[07:52:31.727675] callback:320 [0x100079510]: Volume[0..100] 54
[07:52:31.727849] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 43


[07:52:34.494998] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:34.495026] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.128585
[07:52:34.495034] CtrlSetVolume:252 [0x100079510]: uPNP volume 52 (cookie 0x57)
[07:52:34.495139] callback:320 [0x100079510]: Volume[0..100] 52
[07:52:34.495292] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 44


[07:52:34.551347] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:34.551375] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.466070
[07:52:34.551383] CtrlSetVolume:252 [0x100079510]: uPNP volume 51 (cookie 0x58)
[07:52:34.551482] callback:320 [0x100079510]: Volume[0..100] 51
[07:52:34.551593] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 45


[07:52:34.571618] ActionHandler:476 Error in action callback -- 801 (cookie 0x58)
[07:52:34.654021] ProcessEvent:386 [0x100079510]: UPnP Volume local change 52
[07:52:34.686319] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:34.686351] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.715626
[07:52:34.686361] CtrlSetVolume:252 [0x100079510]: uPNP volume 50 (cookie 0x59)
[07:52:34.686466] callback:320 [0x100079510]: Volume[0..100] 50
[07:52:34.686561] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 46


[07:52:34.912363] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:1292 R:1292]
[07:52:34.925832] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:1294 R:1293] [R:0 S:0]
[07:52:36.836293] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:36.836328] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -14.965881
[07:52:36.836337] CtrlSetVolume:252 [0x100079510]: uPNP volume 50 (cookie 0x61)
[07:52:36.836440] callback:320 [0x100079510]: Volume[0..100] 50
[07:52:36.836546] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 47


[07:52:36.992398] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:36.992425] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.303460
[07:52:36.992433] CtrlSetVolume:252 [0x100079510]: uPNP volume 48 (cookie 0x62)
[07:52:36.992556] callback:320 [0x100079510]: Volume[0..100] 48
[07:52:36.992667] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 48


[07:52:38.999304] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:1804 R:1804]
[07:52:39.010981] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:1806 R:1805] [R:0 S:0]
[07:52:40.468816] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:40.468848] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.640239
[07:52:40.468857] CtrlSetVolume:252 [0x100079510]: uPNP volume 47 (cookie 0x6e)
[07:52:40.468959] callback:320 [0x100079510]: Volume[0..100] 47
[07:52:40.469063] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 49


[07:52:41.503739] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:41.503768] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.977247
[07:52:41.503776] CtrlSetVolume:252 [0x100079510]: uPNP volume 46 (cookie 0x72)
[07:52:41.503880] callback:320 [0x100079510]: Volume[0..100] 46
[07:52:41.504018] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 50


[07:52:41.538972] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:41.539008] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.314846
[07:52:41.539069] CtrlSetVolume:252 [0x100079510]: uPNP volume 45 (cookie 0x73)
[07:52:41.539187] callback:320 [0x100079510]: Volume[0..100] 45
[07:52:41.539391] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 51


[07:52:41.545027] ActionHandler:476 Error in action callback -- 801 (cookie 0x73)
[07:52:41.595204] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:41.595234] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.651663
[07:52:41.595242] CtrlSetVolume:252 [0x100079510]: uPNP volume 44 (cookie 0x74)
[07:52:41.595350] callback:320 [0x100079510]: Volume[0..100] 44
[07:52:41.595459] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 52


[07:52:41.658615] ProcessEvent:386 [0x100079510]: UPnP Volume local change 46
[07:52:41.661094] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:41.661114] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.988941
[07:52:41.661122] CtrlSetVolume:252 [0x100079510]: uPNP volume 43 (cookie 0x75)
[07:52:41.661224] callback:320 [0x100079510]: Volume[0..100] 43
[07:52:41.661315] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 53


[07:52:42.111391] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:42.111428] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.651663
[07:52:42.111437] CtrlSetVolume:252 [0x100079510]: uPNP volume 44 (cookie 0x77)
[07:52:42.111546] callback:320 [0x100079510]: Volume[0..100] 44
[07:52:42.111598] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 54


[07:52:42.156439] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:42.156469] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.314846
[07:52:42.156477] CtrlSetVolume:252 [0x100079510]: uPNP volume 45 (cookie 0x78)
[07:52:42.156582] callback:320 [0x100079510]: Volume[0..100] 45
[07:52:42.156749] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 55


[07:52:42.224178] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:42.224204] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -15.977247
[07:52:42.224213] CtrlSetVolume:252 [0x100079510]: uPNP volume 46 (cookie 0x79)
[07:52:42.224317] callback:320 [0x100079510]: Volume[0..100] 46
[07:52:42.224347] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 56


[07:52:43.086214] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:2316 R:2316]
[07:52:43.102700] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:2319 R:2317] [R:0 S:0]
[07:52:43.551184] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:43.551228] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.314846
[07:52:43.551238] CtrlSetVolume:252 [0x100079510]: uPNP volume 45 (cookie 0x7f)
[07:52:43.551423] callback:320 [0x100079510]: Volume[0..100] 45
[07:52:43.551530] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 57


[07:52:43.586237] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:43.586292] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.651663
[07:52:43.586303] CtrlSetVolume:252 [0x100079510]: uPNP volume 44 (cookie 0x80)
[07:52:43.586411] callback:320 [0x100079510]: Volume[0..100] 44
[07:52:43.586464] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 58


[07:52:43.698871] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:52:43.698901] handle_rtsp:492 [0x1001110f0]: SET PARAMETER volume -16.988941
[07:52:43.698910] CtrlSetVolume:252 [0x100079510]: uPNP volume 43 (cookie 0x81)
[07:52:43.699035] callback:320 [0x100079510]: Volume[0..100] 43
[07:52:43.699079] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 59


[07:52:47.173198] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:2828 R:2828]
[07:52:47.185607] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:2830 R:2829] [R:0 S:0]
[07:52:51.260093] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:3340 R:3340]
[07:52:51.268173] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:3342 R:3341] [R:0 S:0]
[07:52:55.347072] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:3852 R:3852]
[07:52:55.362483] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:3854 R:3853] [R:0 S:0]
[07:52:59.434104] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:4364 R:4364]
[07:52:59.445637] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:4366 R:4365] [R:0 S:0]
[07:53:03.520312] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:4876 R:4876]
[07:53:03.529761] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:4878 R:4877] [R:0 S:0]
[07:53:07.607228] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:5388 R:5388]
[07:53:07.620799] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:5390 R:5389] [R:0 S:0]
[07:53:11.694329] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:5900 R:5900]
[07:53:11.710265] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:5902 R:5901] [R:0 S:0]
[07:53:15.781715] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:6412 R:6412]
[07:53:15.797755] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:6415 R:6413] [R:0 S:0]
[07:53:19.868683] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:6924 R:6924]
[07:53:19.884064] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:6926 R:6925] [R:0 S:0]
[07:53:23.955632] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:7436 R:7436]
[07:53:23.965227] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:7438 R:7437] [R:0 S:0]
[07:53:28.041794] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:7948 R:7948]
[07:53:28.058354] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:7950 R:7949] [R:0 S:0]
[07:53:32.129503] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:8460 R:8460]
[07:53:32.142763] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:8462 R:8461] [R:0 S:0]
[07:53:36.215617] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:8972 R:8972]
[07:53:36.228128] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:8974 R:8973] [R:0 S:0]
[07:53:40.303384] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:9484 R:9484]
[07:53:40.313765] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:9486 R:9485] [R:0 S:0]
[07:53:44.389584] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:9996 R:9996]
[07:53:44.400581] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:9998 R:9997] [R:0 S:0]
[07:53:48.477285] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:10508 R:10508]
[07:53:48.489372] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:10510 R:10509] [R:0 S:0]
[07:53:52.563539] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:11020 R:11020]
[07:53:52.579489] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:11022 R:11021] [R:0 S:0]
[07:53:56.650944] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:11532 R:11532]
[07:53:56.663175] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:11534 R:11533] [R:0 S:0]
[07:54:00.737366] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:12044 R:12044]
[07:54:00.753785] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:12046 R:12045] [R:0 S:0]
[07:54:04.824977] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:12556 R:12556]
[07:54:04.835133] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:12558 R:12557] [R:0 S:0]
[07:54:08.911268] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:13068 R:13068]
[07:54:08.926197] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:13070 R:13069] [R:0 S:0]
[07:54:12.998626] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:13580 R:13580]
[07:54:13.016220] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:13583 R:13581] [R:0 S:0]
[07:54:17.085059] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:14092 R:14092]
[07:54:17.101780] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:14095 R:14093] [R:0 S:0]
[07:54:19.265103] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -16 (skip: 0)
[07:54:21.172764] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:14604 R:14604]
[07:54:21.193496] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:14607 R:14606] [R:0 S:0]
[07:54:23.287509] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -8 (skip: 0)
[07:54:25.259650] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:15116 R:15116]
[07:54:25.286430] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:15120 R:15119] [R:0 S:0]
[07:54:29.346692] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:15628 R:15628]
[07:54:29.375181] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:15632 R:15631] [R:0 S:0]
[07:54:31.333779] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -1 (skip: 0)
[07:54:33.433337] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:16140 R:16140]
[07:54:33.469767] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:16145 R:16144] [R:0 S:0]
[07:54:37.520475] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:16652 R:16652]
[07:54:37.558153] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:16657 R:16656] [R:0 S:0]
[07:54:41.606634] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:17164 R:17164]
[07:54:41.641648] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:17169 R:17168] [R:0 S:0]
[07:54:45.694354] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:17676 R:17676]
[07:54:45.733949] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:17681 R:17680] [R:0 S:0]
[07:54:49.780594] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:18188 R:18188]
[07:54:49.814401] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:18193 R:18192] [R:0 S:0]
[07:54:53.868301] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:18700 R:18700]
[07:54:53.906780] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:18705 R:18704] [R:0 S:0]
[07:54:57.954537] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:19212 R:19212]
[07:54:57.995308] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:19218 R:19216] [R:0 S:0]
[07:55:02.041335] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:19724 R:19724]
[07:55:02.075405] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:19729 R:19728] [R:0 S:0]
[07:55:06.128383] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:20236 R:20236]
[07:55:06.168796] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:20241 R:20240] [R:0 S:0]
[07:55:10.216064] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:20748 R:20748]
[07:55:10.255777] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:20753 R:20752] [R:0 S:0]
[07:55:14.302113] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:21260 R:21260]
[07:55:14.338798] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:21265 R:21264] [R:0 S:0]
[07:55:18.389816] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:21772 R:21772]
[07:55:18.431666] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:21778 R:21776] [R:0 S:0]
[07:55:20.243103] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:20.243143] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 65


[07:55:20.244486] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:20.244512] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 66


[07:55:20.245815] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:20.245843] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 67


[07:55:20.249791] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[07:55:22.476321] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:22284 R:22283]
[07:55:22.514690] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:22289 R:22288] [R:0 S:0]
[07:55:26.563831] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:22796 R:22796]
[07:55:26.595893] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:22801 R:22800] [R:0 S:0]
[07:55:30.649891] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:23308 R:23308]
[07:55:30.687102] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:23313 R:23312] [R:0 S:0]
[07:55:34.737662] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:23820 R:23819]
[07:55:34.774662] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:23825 R:23824] [R:0 S:0]
[07:55:35.925667] handle_rtsp:337 [0x1001110f0]: received FLUSH
[07:55:35.925793] callback:284 [0x100079510]: Flush
[07:55:35.925803] AVTStop:216 [0x100079510]: uPNP stop (cookie 0x280)
[07:55:35.926027] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 68


[07:55:35.982896] read_line:1121 fd: 17 read error: Connection reset by peer
[07:55:35.982933] http_parse:1042 cannot read method
[07:55:35.982946] http_thread_func:877 HTTP close 17
[07:55:36.176856] rtp_thread_func:581 [0x101813a00]: 1st sync packet received
[07:55:36.177031] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[07:55:36.177209] AVTSetURI:90 [0x100079510]: uPNP setURI http://10.0.0.54:64479/stream.flac (cookie 0x281)
[07:55:36.177420] AVTPlay:150 [0x100079510]: uPNP play (cookie 0x282)
[07:55:36.177450] CtrlSetVolume:252 [0x100079510]: uPNP volume 43 (cookie 0x282)
[07:55:36.178458] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:36.178494] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 69


[07:55:36.179971] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:36.180002] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 70


[07:55:36.181305] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:55:36.181334] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 71


[07:55:36.280649] http_thread_func:860 [0x101813a00]: got HTTP connection 18 (silent frames 375)
[07:55:36.281663] handle_http:996 [0x101813a00]: received GET
[07:55:36.281691] handle_http:1013 [0x101813a00]: responding:
HTTP/1.0 200 OK
Server: HairTunes
Content-Type: audio/flac
Connection: close


[07:55:36.746215] ActionHandler:462 [0x100079510]: uPNP playing
[07:55:39.075140] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:24334 R:24334]
[07:55:39.122012] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:24340 R:24339] [R:0 S:0]
[07:55:43.162217] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:24846 R:24846]
[07:55:43.208170] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:24852 R:24851] [R:0 S:0]
[07:55:47.249207] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:25358 R:25358]
[07:55:47.289677] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:25364 R:25363] [R:0 S:0]
[07:55:51.336189] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:25870 R:25870]
[07:55:51.381820] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:25876 R:25875] [R:0 S:0]
[07:55:55.422486] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:26382 R:26382]
[07:55:55.467313] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:26388 R:26387] [R:0 S:0]
[07:55:59.509157] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:26894 R:26893]
[07:55:59.556009] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:26900 R:26899] [R:0 S:0]
[07:56:03.597028] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:27406 R:27406]
[07:56:03.644366] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:27412 R:27411] [R:0 S:0]
[07:56:07.683863] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:27918 R:27918]
[07:56:07.731850] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:27925 R:27923] [R:0 S:0]
[07:56:11.770574] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:28430 R:28430]
[07:56:11.816988] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:28436 R:28435] [R:0 S:0]
[07:56:15.857791] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:28942 R:28942]
[07:56:15.897092] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:28948 R:28947] [R:0 S:0]
[07:56:19.944798] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:29454 R:29454]
[07:56:19.994359] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:29461 R:29459] [R:0 S:0]
[07:56:24.031676] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:29966 R:29966]
[07:56:24.071365] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:29972 R:29971] [R:0 S:0]
[07:56:28.118601] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:30478 R:30478]
[07:56:28.159011] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:30484 R:30483] [R:0 S:0]
[07:56:32.205551] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:30990 R:30990]
[07:56:32.249750] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:30996 R:30995] [R:0 S:0]
[07:56:36.292255] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:31502 R:31501]
[07:56:36.331603] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:31508 R:31507] [R:0 S:0]
[07:56:40.379249] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:32014 R:32014]
[07:56:40.423382] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:32020 R:32019] [R:0 S:0]
[07:56:43.564552] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -14 (skip: 0)
[07:56:44.465783] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:32526 R:32526]
[07:56:44.519710] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:32533 R:32532] [R:0 S:0]
[07:56:47.586952] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -7 (skip: 0)
[07:56:48.553287] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:33038 R:33038]
[07:56:48.609268] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:33046 R:33045] [R:0 S:0]
[07:56:52.639480] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:33550 R:33550]
[07:56:52.700438] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:33558 R:33557] [R:0 S:0]
[07:56:56.727184] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:34062 R:34062]
[07:56:56.783312] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:34070 R:34069] [R:0 S:0]
[07:57:00.814147] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:34574 R:34574]
[07:57:00.870826] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:34582 R:34581] [R:0 S:0]
[07:57:04.900677] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:35086 R:35086]
[07:57:04.958971] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:35094 R:35093] [R:0 S:0]
[07:57:08.987965] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:35598 R:35598]
[07:57:09.044471] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:35606 R:35605] [R:0 S:0]
[07:57:13.074167] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:36110 R:36109]
[07:57:13.131055] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:36118 R:36117] [R:0 S:0]
[07:57:17.161292] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:36622 R:36622]
[07:57:17.217879] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:36630 R:36629] [R:0 S:0]
[07:57:21.248235] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:37134 R:37134]
[07:57:21.310884] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:37142 R:37141] [R:0 S:0]
[07:57:25.335770] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:37646 R:37646]
[07:57:25.392776] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:37654 R:37653] [R:0 S:0]
[07:57:29.422208] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:38158 R:38158]
[07:57:29.478322] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:38166 R:38165] [R:0 S:0]
[07:57:33.509569] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:38670 R:38670]
[07:57:33.571512] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:38678 R:38677] [R:0 S:0]
[07:57:37.596564] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:39182 R:39182]
[07:57:37.655766] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:39190 R:39189] [R:0 S:0]
[07:57:41.683476] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:39694 R:39694]
[07:57:41.744641] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:39702 R:39701] [R:0 S:0]
[07:57:45.769617] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:40206 R:40206]
[07:57:45.828503] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:40214 R:40213] [R:0 S:0]
[07:57:49.856996] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:40718 R:40718]
[07:57:49.912697] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:40726 R:40725] [R:0 S:0]
[07:57:53.944401] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:41230 R:41230]
[07:57:54.002036] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:41238 R:41237] [R:0 S:0]
[07:57:58.030351] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:41742 R:41742]
[07:57:58.087214] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:41750 R:41749] [R:0 S:0]
[07:58:02.117757] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:42254 R:42254]
[07:58:02.180797] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:42262 R:42261] [R:0 S:0]
[07:58:06.204690] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:42766 R:42766]
[07:58:06.266146] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:42774 R:42773] [R:0 S:0]
[07:58:10.291976] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:43278 R:43278]
[07:58:10.347965] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:43286 R:43285] [R:0 S:0]
[07:58:14.379074] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:43790 R:43790]
[07:58:14.434706] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:43798 R:43797] [R:0 S:0]
[07:58:18.467306] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:44302 R:44302]
[07:58:18.521317] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:44310 R:44309] [R:0 S:0]
[07:58:22.552889] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:44814 R:44814]
[07:58:22.616327] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:44822 R:44821] [R:0 S:0]
[07:58:24.141457] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -12 (skip: 0)
[07:58:26.639184] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:45326 R:45325]
[07:58:26.710921] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:45335 R:45334] [R:0 S:0]
[07:58:28.164436] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -5 (skip: 0)
[07:58:30.726839] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:45838 R:45838]
[07:58:30.798305] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:45848 R:45847] [R:0 S:0]
[07:58:34.813097] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:46350 R:46350]
[07:58:34.886361] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:46360 R:46359] [R:0 S:0]
[07:58:37.847887] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:58:37.847930] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 78


[07:58:37.849581] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:58:37.849626] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 79


[07:58:37.851031] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[07:58:37.851058] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 80


[07:58:37.854973] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[07:58:38.900638] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:46862 R:46862]
[07:58:38.976549] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:46872 R:46871] [R:0 S:0]
[07:58:42.987685] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:47374 R:47374]
[07:58:43.064055] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:47384 R:47383] [R:0 S:0]
[07:58:47.073906] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:47886 R:47886]
[07:58:47.149962] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:47896 R:47895] [R:0 S:0]
[07:58:51.160595] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:48398 R:48398]
[07:58:51.232963] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:48408 R:48407] [R:0 S:0]
[07:58:55.247861] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:48910 R:48909]
[07:58:55.323457] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:48920 R:48919] [R:0 S:0]
[07:58:59.335420] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:49422 R:49422]
[07:58:59.407686] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:49432 R:49431] [R:0 S:0]
[07:59:03.421362] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:49934 R:49934]
[07:59:03.502292] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:49945 R:49943] [R:0 S:0]
[07:59:07.509207] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:50446 R:50446]
[07:59:07.588799] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:50456 R:50455] [R:0 S:0]
[07:59:11.596267] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:50958 R:50958]
[07:59:11.675486] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:50968 R:50967] [R:0 S:0]
[07:59:15.683100] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:51470 R:51470]
[07:59:15.755294] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:51480 R:51479] [R:0 S:0]
[07:59:19.770057] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:51982 R:51982]
[07:59:19.842120] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:51992 R:51991] [R:0 S:0]
[07:59:23.856846] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:52494 R:52493]
[07:59:23.932276] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:52504 R:52503] [R:0 S:0]
[07:59:27.943732] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:53006 R:53006]
[07:59:28.019065] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:53016 R:53015] [R:0 S:0]
[07:59:32.030773] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:53518 R:53517]
[07:59:32.110567] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:53528 R:53527] [R:0 S:0]
[07:59:36.116870] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:54030 R:54030]
[07:59:36.194361] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:54040 R:54039] [R:0 S:0]
[07:59:40.204649] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:54542 R:54542]
[07:59:40.277266] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:54552 R:54551] [R:0 S:0]
[07:59:44.291174] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:55054 R:55054]
[07:59:44.363982] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:55064 R:55063] [R:0 S:0]
[07:59:48.378583] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:55566 R:55566]
[07:59:48.451485] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:55576 R:55575] [R:0 S:0]
[07:59:52.465261] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:56078 R:56078]
[07:59:52.537528] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:56088 R:56087] [R:0 S:0]
[07:59:56.551817] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:56590 R:56590]
[07:59:56.631460] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:56600 R:56599] [R:0 S:0]
[08:00:00.638622] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:57102 R:57102]
[08:00:00.714638] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:57112 R:57111] [R:0 S:0]
[08:00:04.725602] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:57614 R:57614]
[08:00:04.800243] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:57624 R:57623] [R:0 S:0]
[08:00:08.812765] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:58126 R:58126]
[08:00:08.886595] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:58136 R:58135] [R:0 S:0]
[08:00:12.900206] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:58638 R:58638]
[08:00:12.972152] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:58648 R:58647] [R:0 S:0]
[08:00:16.987009] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:59150 R:59150]
[08:00:17.062682] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:59160 R:59159] [R:0 S:0]
[08:00:20.443572] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -14 (skip: 0)
[08:00:21.073442] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:59662 R:59662]
[08:00:21.159045] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:59673 R:59672] [R:0 S:0]
[08:00:24.466576] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -7 (skip: 0)
[08:00:25.160462] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:60174 R:60174]
[08:00:25.254277] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:60186 R:60185] [R:0 S:0]
[08:00:29.248072] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:60686 R:60686]
[08:00:29.339771] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:60698 R:60697] [R:0 S:0]
[08:00:33.334608] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:61198 R:61198]
[08:00:33.425611] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:61210 R:61209] [R:0 S:0]
[08:00:37.421766] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:61710 R:61710]
[08:00:37.511726] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:61722 R:61721] [R:0 S:0]
[08:00:41.508464] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:62222 R:62222]
[08:00:41.599198] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:62234 R:62233] [R:0 S:0]
[08:00:45.595782] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:62734 R:62734]
[08:00:45.689812] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:62746 R:62745] [R:0 S:0]
[08:00:49.681943] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:63246 R:63245]
[08:00:49.776602] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:63258 R:63257] [R:0 S:0]
[08:00:53.769540] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:63758 R:63758]
[08:00:53.857637] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:63770 R:63769] [R:0 S:0]
[08:00:54.473518] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:00:54.473557] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 86


[08:00:54.474991] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:00:54.475022] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 87


[08:00:54.476321] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:00:54.476351] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 88


[08:00:54.479988] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[08:00:57.856468] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:64270 R:64269]
[08:00:57.946256] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:64282 R:64281] [R:0 S:0]
[08:01:01.943110] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:64782 R:64782]
[08:01:02.033991] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:64794 R:64793] [R:0 S:0]
[08:01:06.030367] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:65294 R:65294]
[08:01:06.121199] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:65306 R:65305] [R:0 S:0]
[08:01:10.117345] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:270 R:270]
[08:01:10.207893] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:282 R:281] [R:0 S:0]
[08:01:14.204125] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:782 R:781]
[08:01:14.298063] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:794 R:793] [R:0 S:0]
[08:01:18.291224] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:1294 R:1294]
[08:01:18.381835] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:1306 R:1305] [R:0 S:0]
[08:01:18.627596] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:18.627642] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 89


[08:01:18.629214] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:18.629247] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 90


[08:01:18.630528] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:18.630551] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 91


[08:01:18.633637] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[08:01:22.377896] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:1806 R:1806]
[08:01:22.472608] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:1818 R:1817] [R:0 S:0]
[08:01:26.465121] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:2318 R:2318]
[08:01:26.560769] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:2330 R:2329] [R:0 S:0]
[08:01:30.552073] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:2830 R:2830]
[08:01:30.643930] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:2842 R:2841] [R:0 S:0]
[08:01:33.758221] handle_rtsp:337 [0x1001110f0]: received FLUSH
[08:01:33.758305] callback:284 [0x100079510]: Flush
[08:01:33.758314] AVTStop:216 [0x100079510]: uPNP stop (cookie 0x6a7)
[08:01:33.758419] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 92


[08:01:33.795478] http_thread_func:877 HTTP close 18
[08:01:33.866346] ActionHandler:458 [0x100079510]: uPNP stopped
[08:01:34.011074] rtp_thread_func:581 [0x101813a00]: 1st sync packet received
[08:01:34.011114] rtp_thread_func:557 [0x101813a00]: 1st audio packet received
[08:01:34.011292] AVTSetURI:90 [0x100079510]: uPNP setURI http://10.0.0.54:64479/stream.flac (cookie 0x6a9)
[08:01:34.011500] AVTPlay:150 [0x100079510]: uPNP play (cookie 0x6aa)
[08:01:34.011533] CtrlSetVolume:252 [0x100079510]: uPNP volume 43 (cookie 0x6aa)
[08:01:34.012475] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:34.012507] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 93


[08:01:34.013952] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:34.013978] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 94


[08:01:34.015278] handle_rtsp:337 [0x1001110f0]: received SET_PARAMETER
[08:01:34.015304] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 95


[08:01:34.094156] http_thread_func:860 [0x101813a00]: got HTTP connection 17 (silent frames 375)
[08:01:34.094958] handle_http:996 [0x101813a00]: received GET
[08:01:34.094999] handle_http:1013 [0x101813a00]: responding:
HTTP/1.0 200 OK
Server: HairTunes
Content-Type: audio/flac
Connection: close


[08:01:34.408291] ActionHandler:462 [0x100079510]: uPNP playing
[08:01:34.889889] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:3344 R:3344]
[08:01:34.985563] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:3357 R:3356] [R:0 S:0]
[08:01:38.976575] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:3856 R:3856]
[08:01:39.079739] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:3869 R:3868] [R:0 S:0]
[08:01:43.063555] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:4368 R:4368]
[08:01:43.162425] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:4381 R:4380] [R:0 S:0]
[08:01:47.150588] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:4880 R:4880]
[08:01:47.254708] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:4894 R:4892] [R:0 S:0]
[08:01:51.237727] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:5392 R:5392]
[08:01:51.338274] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:5405 R:5404] [R:0 S:0]
[08:01:55.323977] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:5904 R:5904]
[08:01:55.427984] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:5917 R:5916] [R:0 S:0]
[08:01:59.155885] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -15 (skip: 1)
[08:01:59.411374] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:6416 R:6416]
[08:01:59.515792] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:6430 R:6429] [R:0 S:0]
[08:02:03.179379] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -7 (skip: 0)
[08:02:03.497857] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:6928 R:6928]
[08:02:03.612886] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:6943 R:6942] [R:0 S:0]
[08:02:07.584709] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:7440 R:7440]
[08:02:07.698426] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:7455 R:7454] [R:0 S:0]
[08:02:11.672267] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:7952 R:7952]
[08:02:11.786606] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:7967 R:7966] [R:0 S:0]
[08:02:15.758471] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:8464 R:8464]
[08:02:15.878015] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:8479 R:8478] [R:0 S:0]
[08:02:19.846255] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:8976 R:8976]
[08:02:19.965573] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:8991 R:8990] [R:0 S:0]
[08:02:23.933174] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:9488 R:9488]
[08:02:24.044443] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:9503 R:9502] [R:0 S:0]
[08:02:28.020148] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:10000 R:10000]
[08:02:28.136120] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:10015 R:10014] [R:0 S:0]
[08:02:32.106965] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:10512 R:10512]
[08:02:32.225256] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:10527 R:10526] [R:0 S:0]
[08:02:36.193124] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:11024 R:11024]
[08:02:36.309534] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:11039 R:11038] [R:0 S:0]
[08:02:40.281015] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:11536 R:11536]
[08:02:40.392332] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:11551 R:11550] [R:0 S:0]
[08:02:44.367847] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:12048 R:12048]
[08:02:44.487749] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:12064 R:12062] [R:0 S:0]
[08:02:48.454829] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:12560 R:12560]
[08:02:48.574301] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:12575 R:12574] [R:0 S:0]
[08:02:52.541731] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:13072 R:13072]
[08:02:52.655354] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:13087 R:13086] [R:0 S:0]
[08:02:56.628688] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:13584 R:13584]
[08:02:56.748309] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:13600 R:13598] [R:0 S:0]
[08:03:00.715148] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:14096 R:14096]
[08:03:00.833999] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:14111 R:14110] [R:0 S:0]
[08:03:04.801807] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:14608 R:14608]
[08:03:04.917366] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:14623 R:14622] [R:0 S:0]
[08:03:08.889490] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:15120 R:15120]
[08:03:09.006122] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:15135 R:15134] [R:0 S:0]
[08:03:12.976489] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:15632 R:15632]
[08:03:13.093576] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:15647 R:15646] [R:0 S:0]
[08:03:17.063483] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:16144 R:16144]
[08:03:17.178115] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:16159 R:16158] [R:0 S:0]
[08:03:21.149520] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:16656 R:16656]
[08:03:21.261920] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:16671 R:16670] [R:0 S:0]
[08:03:25.237274] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:17168 R:17168]
[08:03:25.358162] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:17184 R:17182] [R:0 S:0]
[08:03:29.323506] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:17680 R:17680]
[08:03:29.443820] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:17695 R:17694] [R:0 S:0]
[08:03:33.410704] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:18192 R:18192]
[08:03:33.532048] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:18208 R:18206] [R:0 S:0]
[08:03:35.710125] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -15 (skip: 0)
[08:03:37.497627] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:18704 R:18704]
[08:03:37.622327] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:18720 R:18719] [R:0 S:0]
[08:03:39.732687] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -7 (skip: 0)
[08:03:41.585031] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:19216 R:19215]
[08:03:41.720553] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:19233 R:19232] [R:0 S:0]
[08:03:45.671704] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:19728 R:19728]
[08:03:45.805289] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:19745 R:19744] [R:0 S:0]
[08:03:49.758560] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:20240 R:20240]
[08:03:49.895541] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:20258 R:20256] [R:0 S:0]
[08:03:53.845791] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:20752 R:20751]
[08:03:53.975441] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:20769 R:20768] [R:0 S:0]
[08:03:57.931877] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:21264 R:21264]
[08:03:58.061145] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:21281 R:21280] [R:0 S:0]
[08:04:02.019741] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:21776 R:21776]
[08:04:02.153725] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:21793 R:21792] [R:0 S:0]
[08:04:06.106577] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:22288 R:22287]
[08:04:06.237650] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:22305 R:22304] [R:0 S:0]
[08:04:10.193665] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:22800 R:22800]
[08:04:10.329276] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:22817 R:22816] [R:0 S:0]
[08:04:14.280523] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:23312 R:23312]
[08:04:14.414381] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:23329 R:23328] [R:0 S:0]
[08:04:18.367481] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:23824 R:23824]
[08:04:18.497030] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:23841 R:23840] [R:0 S:0]
[08:04:22.454342] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:24336 R:24336]
[08:04:22.589001] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:24353 R:24352] [R:0 S:0]
[08:04:26.540453] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:24848 R:24848]
[08:04:26.672706] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:24865 R:24864] [R:0 S:0]
[08:04:30.628335] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:25360 R:25360]
[08:04:30.763076] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:25377 R:25376] [R:0 S:0]
[08:04:34.715163] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:25872 R:25872]
[08:04:34.846740] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:25889 R:25888] [R:0 S:0]
[08:04:38.801811] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:26384 R:26384]
[08:04:38.932242] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:26401 R:26400] [R:0 S:0]
[08:04:42.889102] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:26896 R:26896]
[08:04:43.018845] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:26913 R:26912] [R:0 S:0]
[08:04:46.976068] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:27408 R:27408]
[08:04:47.107246] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:27425 R:27424] [R:0 S:0]
[08:04:51.062826] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:27920 R:27920]
[08:04:51.196008] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:27937 R:27936] [R:0 S:0]
[08:04:55.149891] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:28432 R:28432]
[08:04:55.277916] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:28449 R:28448] [R:0 S:0]
[08:04:59.236906] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:28944 R:28944]
[08:04:59.371188] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:28961 R:28960] [R:0 S:0]
[08:05:03.323774] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:29456 R:29456]
[08:05:03.455441] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:29473 R:29472] [R:0 S:0]
[08:05:07.410577] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:29968 R:29968]
[08:05:07.546380] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:29985 R:29984] [R:0 S:0]
[08:05:11.496809] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:30480 R:30480]
[08:05:11.632417] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:30497 R:30496] [R:0 S:0]
[08:05:15.584595] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:30992 R:30992]
[08:05:15.714560] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:31009 R:31008] [R:0 S:0]
[08:05:19.670594] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:31504 R:31504]
[08:05:19.802494] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:31521 R:31520] [R:0 S:0]
[08:05:20.310318] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -15 (skip: 0)
[08:05:23.758577] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:32016 R:32016]
[08:05:23.900069] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:32034 R:32033] [R:0 S:0]
[08:05:24.333326] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -8 (skip: 0)
[08:05:27.845343] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:32528 R:32528]
[08:05:27.994523] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:32547 R:32546] [R:0 S:0]
[08:05:28.356432] rtp_thread_func:640 [0x101813a00]: Sending packets too slow -1 (skip: 0)
[08:05:31.932331] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:33040 R:33040]
[08:05:32.092116] buffer_get_frame:780 [0x101813a00]: drain status [level:2] [W:33061 R:33059] [R:0 S:0]
[08:05:36.019300] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:33552 R:33552]
[08:05:36.173322] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:33572 R:33571] [R:0 S:0]
[08:05:40.106185] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:34064 R:34064]
[08:05:40.262349] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:34084 R:34083] [R:0 S:0]
[08:05:44.193034] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:34576 R:34575]
[08:05:44.348365] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:34596 R:34595] [R:0 S:0]
[08:05:48.279397] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:35088 R:35088]
[08:05:48.433101] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:35108 R:35107] [R:0 S:0]
[08:05:52.367012] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:35600 R:35600]
[08:05:52.522342] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:35620 R:35619] [R:0 S:0]
[08:05:56.453133] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:36112 R:36112]
[08:05:56.612111] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:36132 R:36131] [R:0 S:0]
[08:06:00.540907] buffer_put_packet:445 [0x101813a00]: fill status [level:1] [W:36624 R:36623]
[08:06:00.697026] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:36644 R:36643] [R:0 S:0]
[08:06:04.627799] buffer_put_packet:445 [0x101813a00]: fill status [level:0] [W:37136 R:37136]
[08:06:04.784823] buffer_get_frame:780 [0x101813a00]: drain status [level:1] [W:37156 R:37155] [R:0 S:0]
[08:06:06.822622] http_thread_func:919 [0x101813a00]: spent 663 ms in send!
[08:06:08.513693] http_thread_func:919 [0x101813a00]: spent 1691 ms in send!
[08:06:08.571855] http_thread_func:919 [0x101813a00]: spent 58 ms in send!
[08:06:08.714712] buffer_put_packet:445 [0x101813a00]: fill status [level:276] [W:37648 R:37372]
[08:06:08.865511] http_thread_func:919 [0x101813a00]: spent 187 ms in send!
[08:06:08.948928] http_thread_func:919 [0x101813a00]: spent 83 ms in send!
[08:06:09.042546] http_thread_func:919 [0x101813a00]: spent 93 ms in send!
[08:06:09.207831] http_thread_func:919 [0x101813a00]: spent 66 ms in send!
[08:06:09.442083] http_thread_func:919 [0x101813a00]: spent 226 ms in send!
[08:06:09.498631] http_thread_func:919 [0x101813a00]: spent 55 ms in send!
[08:06:09.553745] http_thread_func:919 [0x101813a00]: spent 51 ms in send!
[08:06:09.858957] http_thread_func:919 [0x101813a00]: spent 132 ms in send!
[08:06:10.085843] http_thread_func:919 [0x101813a00]: spent 188 ms in send!
[08:06:12.433754] http_thread_func:919 [0x101813a00]: spent 2347 ms in send!
[08:06:12.801492] buffer_put_packet:445 [0x101813a00]: fill status [level:584] [W:38160 R:37576]
[08:06:13.817138] http_thread_func:919 [0x101813a00]: spent 1383 ms in send!
[08:06:13.870504] http_thread_func:919 [0x101813a00]: spent 53 ms in send!
[08:06:14.373988] http_thread_func:919 [0x101813a00]: spent 503 ms in send!
[08:06:15.834055] http_thread_func:919 [0x101813a00]: spent 1455 ms in send!
[08:06:16.039096] http_thread_func:919 [0x101813a00]: spent 112 ms in send!
[08:06:16.178878] http_thread_func:919 [0x101813a00]: spent 67 ms in send!
[08:06:16.457830] http_thread_func:919 [0x101813a00]: spent 207 ms in send!
[08:06:16.591597] http_thread_func:919 [0x101813a00]: spent 84 ms in send!
[08:06:16.624600] buffer_get_frame:780 [0x101813a00]: drain status [level:972] [W:38639 R:37667] [R:0 S:0]
[08:06:16.887965] buffer_put_packet:445 [0x101813a00]: fill status [level:1003] [W:38672 R:37669]
[08:06:17.343499] http_thread_func:919 [0x101813a00]: spent 719 ms in send!
[08:06:17.343689] buffer_get_frame:750 [0x101813a00]: Buffer overrun 1061
[08:06:18.023800] http_thread_func:919 [0x101813a00]: spent 680 ms in send!
[08:06:18.023839] buffer_get_frame:750 [0x101813a00]: Buffer overrun 1042
[08:06:18.403850] http_thread_func:919 [0x101813a00]: spent 60 ms in send!
[08:06:18.753416] http_thread_func:919 [0x101813a00]: spent 77 ms in send!
[08:06:19.310360] http_thread_func:919 [0x101813a00]: spent 412 ms in send!
[08:06:19.403969] http_thread_func:919 [0x101813a00]: spent 92 ms in send!
[08:06:19.600000] http_thread_func:919 [0x101813a00]: spent 74 ms in send!
[08:06:19.762084] http_thread_func:919 [0x101813a00]: spent 52 ms in send!
[08:06:19.972408] http_thread_func:919 [0x101813a00]: spent 108 ms in send!
[08:06:20.038074] http_thread_func:919 [0x101813a00]: spent 58 ms in send!
[08:06:20.120134] http_thread_func:919 [0x101813a00]: spent 82 ms in send!
[08:06:20.274468] http_thread_func:919 [0x101813a00]: spent 149 ms in send!
[08:06:20.408944] http_thread_func:919 [0x101813a00]: spent 75 ms in send!
[08:06:20.975571] buffer_put_packet:445 [0x101813a00]: fill status [level:928] [W:39184 R:38256]
[08:06:21.671054] http_thread_func:919 [0x101813a00]: spent 1235 ms in send!
[08:06:21.708193] http_thread_func:923 [0x101813a00]: HTTP send() unexpected response: -1 (data=2768): Broken pipe
[08:06:21.708281] read_line:1121 fd: 17 read error: Connection reset by peer
[08:06:21.708289] http_parse:1042 cannot read method
[08:06:21.708329] http_thread_func:877 HTTP close 17
[08:06:21.778774] handle_rtsp:337 [0x1001110f0]: received FLUSH
[08:06:21.778920] callback:284 [0x100079510]: Flush
[08:06:21.778936] AVTStop:216 [0x100079510]: uPNP stop (cookie 0xa02)
[08:06:21.779144] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 106


[08:06:21.799746] raop_notify:257 [0x1001110f0]: sending airplay remote
GET /ctrl-int/1/stop HTTP/1.0
Active-Remote: 1474138000
Connection: close

<== received ==>
HTTP/1.1 204 No Content
Date: Fri, 16 Feb 2018 16:06:21 GMT
DAAP-Server: iTunes/12.7.3.46 (OS X)
Content-Type: application/x-dmap-tagged
Content-Length: 0

[08:06:21.799811] ActionHandler:458 [0x100079510]: uPNP stopped
[08:06:22.262203] ActionHandler:458 [0x100079510]: uPNP stopped
[08:06:33.302780] handle_rtsp:337 [0x1001110f0]: received TEARDOWN
[08:06:33.337317] http_thread_func:940 [0x101813a00]: terminating
[08:06:33.337316] rtp_thread_func:659 [0x101813a00]: terminating
[08:06:33.337765] callback:275 [0x100079510]: Stop
[08:06:33.337817] handle_rtsp:508 [0x1001110f0]: responding:
RTSP/1.0 200 OK
Audio-Jack-Status: connected; type=analog
CSeq: 107


[08:06:35.338748] rtsp_thread:311 RTSP close 11