Hi bubbleguuum,
I found a problem that occurs when playing a specific mp3 album.
I import the songs to the UPnP Controller Playback Queue and when I press the 'skip to next' button it stops playing.
I attached two files from this album and also the foo_upnp.log contents.
two files
0.000: foo_upnp::on_init(199)/4288: INFO: foo_upnp: version 0.99.49
0.016: PLT_UPnP::Start(150)/4288: INFO: Starting UPnP...
0.016: standard_media_tree_root_node_t::load_ml_filters(590)/4288: INFO: loaded Media Library filters from D:\Users\feki\Programs\foobar2000\foo_upnp_ml_tree.xml
0.063: PLT_UPnP::AddDevice(224)/4288: INFO: Starting Device...
0.078: PLT_HttpServer::Start(127)/4288: INFO: HttpServer listening on 0.0.0.0:56923
initialized in 0.094s
0.515: FbMediaServer::CheckAuthentication(401)/3156: INFO: received LAN request: http://192.168.100.107:56923/DeviceDescription.xml (User-Agent: None)
0.905: FbMediaServer::CheckAuthentication(401)/2828: INFO: received LAN request: http://192.168.100.107:56923/DeviceDescription.xml (User-Agent: Network_Module/1.0 (HTR-4068))
1.1139: FbMediaServer::CheckAuthentication(401)/4976: INFO: received LAN request: http://192.168.100.107:56923/DeviceDescription.xml (User-Agent: Linux/3.0.13 UPnP/1.0 LGE_DLNA_SDK/1.6.0 [TV][LG]55UB830V-ZG/04.11.22 DLNADOC/1.50)
1.1422: FbMediaServer::OnAction(295)/4012: INFO: received action 'GetSortCapabilities', args = { }
1.1422: FbMediaServer::OnAction(323)/4012: INFO: action 'GetSortCapabilities' response: args = { SortCaps= }
1.1425: FbMediaServer::CheckAuthentication(401)/4116: INFO: received LAN request: http://192.168.100.107:56923/ContentDirectory/baf06952-b3c7-e51f-7bb8-97cfbb577b24/scpd.xml (User-Agent: Linux/3.0.13 UPnP/1.0 LGE_DLNA_SDK/1.6.0 [TV][LG]55UB830V-ZG/04.11.22 DLNADOC/1.50)
1.1488: FbMediaServer::CheckAuthentication(401)/3296: INFO: received LAN request: http://192.168.100.107:56923/DeviceDescription.xml (User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50)
1.1722: FbMediaServer::CheckAuthentication(401)/3080: INFO: received LAN request: http://192.168.100.107:56923/ConnectionManager/baf06952-b3c7-e51f-7bb8-97cfbb577b24/scpd.xml (User-Agent: Linux/3.0.13 UPnP/1.0 LGE_DLNA_SDK/1.6.0 [TV][LG]55UB830V-ZG/04.11.22 DLNADOC/1.50)
1.1831: FbMediaServer::CheckAuthentication(401)/4108: INFO: received LAN request: http://192.168.100.107:56923/X_MS_MediaReceiverRegistrar/baf06952-b3c7-e51f-7bb8-97cfbb577b24/scpd.xml (User-Agent: Linux/3.0.13 UPnP/1.0 LGE_DLNA_SDK/1.6.0 [TV][LG]55UB830V-ZG/04.11.22 DLNADOC/1.50)
1.1925: FbMediaServer::CheckAuthentication(401)/720: INFO: received LAN request: http://192.168.100.107:56923/Control/baf06952-b3c7-e51f-7bb8-97cfbb577b24/scpd.xml (User-Agent: Linux/3.0.13 UPnP/1.0 LGE_DLNA_SDK/1.6.0 [TV][LG]55UB830V-ZG/04.11.22 DLNADOC/1.50)
2.2221: FbMediaServer::OnAction(295)/1308: INFO: received action 'GetSystemUpdateID', args = { }
2.2221: FbMediaServer::OnAction(323)/1308: INFO: action 'GetSystemUpdateID' response: args = { Id=0 }
created 39241 path registry entries in 3.952s
4.4093: FbMediaServer::CheckAuthentication(401)/1412: INFO: received LAN request: http://192.168.100.107:56923/DeviceDescription.xml (User-Agent: Microsoft-Windows/6.1 UPnP/1.0 Windows-Media-Player-DMS/12.0.7601.17514 DLNADOC/1.50)
4.4265: FbMediaServer::OnAction(295)/3836: INFO: received action 'GetSearchCapabilities', args = { }
4.4265: FbMediaServer::OnAction(323)/3836: INFO: action 'GetSearchCapabilities' response: args = { SearchCaps=upnp:class,dc:title,upnp:artist }
4.4296: FbMediaServer::OnAction(295)/152: INFO: received action 'Browse', args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, StartingIndex=0, RequestedCount=100, SortCriteria= }
4.4296: FbMediaServer::on_browse_direct_children(1409)/152: INFO: Server not ready
4.4296: FbMediaServer::OnAction(323)/152: INFO: action 'Browse' response: args = { ObjectID=0, BrowseFlag=BrowseDirectChildren, Filter=res@resolution,res@nrAudioChannels,res@sampleFrequency,res@bitrate,dc:creator,res@dlna:cleartextSize,dc:date,upnp:genre,res,res@duration,res@size,upnp:albumArtURI,upnp:originalTrackNumber,upnp:album,upnp:artist,upnp:author, StartingIndex=0, RequestedCount=100, SortCriteria= }
4.4296: PLT_DeviceHost::ProcessHttpPostRequest(599)/152: WARNING: Error while processing action Browse: 503 Service Unavailable
loaded Media Library tree in 34.276s
40.40472: controller_window::set_track_info_string(1112)/4288: INFO: track info display:
40.40472: PLT_UPnP::AddCtrlPoint(266)/4288: INFO: Starting Ctrlpoint...
40.40476: PLT_HttpServer::Start(127)/4288: INFO: HttpServer listening on 0.0.0.0:1854
40.40487: PLT_CtrlPoint::InspectDevice(1269)/1808: INFO: Inspecting device "6B8B4567-23C6-9869-E4F5-0418D6AE6D0A" detected @ http://192.168.100.254:54969/rootDesc.xml
40.40579: PLT_CtrlPoint::InspectDevice(1269)/1808: INFO: Inspecting device "9ab0c000-f668-11de-9976-00a0ded0160f" detected @ http://192.168.100.115:49154/MediaRenderer/desc.xml
41.41014: PLT_CtrlPoint::ProcessGetDescriptionResponse(1394)/4412: INFO: Device "6B8B4567-23C6-9869-E4F5-0418D6AE6D0A" is now known as "OpenWRT router"
41.41117: PLT_CtrlPoint::ProcessGetDescriptionResponse(1394)/4988: INFO: Device "9ab0c000-f668-11de-9976-00a0ded0160f" is now known as "HTR-4068 D0160F"
41.41314: LinnDsRendererController::Create(117)/4288: INFO: couldn't find urn:av-openhome-org:service:Product:1 service
41.41314: RendererController::Create(25)/4288: INFO: Created UPnPAv Controller for device: HTR-4068 D0160F
41.41497: PLT_CtrlPoint::InspectDevice(1269)/1808: INFO: Inspecting device "b7e01e12-db36-115d-bf85-b35b0c58135c" detected @ http://192.168.100.110:1647/
41.41557: PLT_CtrlPoint::InspectDevice(1269)/2980: INFO: Inspecting device "060b7353-fca6-4070-85f4-1fbfb9add62c" detected @ http://192.168.0.1:1900/igd.xml
41.41706: PLT_CtrlPoint::InspectDevice(1269)/1808: INFO: Inspecting device "3662dceb-a4f7-3ad5-8d03-104520c4fe90" detected @ http://192.168.100.148:8895/deviceDescription/3662dceb-a4f7-3ad5-8d03-104520c4fe90
42.42018: PLT_CtrlPoint::ProcessGetDescriptionResponse(1394)/3048: INFO: Device "b7e01e12-db36-115d-bf85-b35b0c58135c" is now known as "[TV][LG]55UB830V-ZG"
42.42186: LinnDsRendererController::Create(117)/4288: INFO: couldn't find urn:av-openhome-org:service:Product:1 service
42.42187: RendererController::Create(25)/4288: INFO: Created UPnPAv Controller for device: [TV][LG]55UB830V-ZG
42.42229: PLT_CtrlPoint::ProcessGetDescriptionResponse(1394)/4208: INFO: Device "3662dceb-a4f7-3ad5-8d03-104520c4fe90" is now known as "Serviio (FEKIDESKTOP)"
42.42375: LinnDsRendererController::Create(117)/4288: INFO: couldn't find urn:av-openhome-org:service:Product:1 service
43.43879: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Stopped
43.43880: PLT_CtrlPoint::Subscribe(1562)/4288: INFO: Subscribing to service "urn:upnp-org:serviceId:RenderingControl" of device "HTR-4068 D0160F"
43.43886: PLT_CtrlPoint::ProcessSubscribeResponse(1666)/4308: INFO: Creating new subscriber "uuid:9ab0c000-f668-11de-9976-6412572b7c8e" for service "urn:upnp-org:serviceId:RenderingControl" of device "HTR-4068 D0160F" (timeout = 300)
43.43892: UPnPAVRendererController::OnEventNotifyCallback(413)/4288: INFO: <<<< HTR-4068 D0160F: EVENT Mute=0
43.43892: UPnPAVRendererController::OnEventNotifyCallback(413)/4288: INFO: <<<< HTR-4068 D0160F: EVENT Volume=72
43.43894: UPnPAVRendererController::OnEventNotifyCallback(413)/4288: INFO: <<<< HTR-4068 D0160F: EVENT PresetNameList=FactoryDefaults
43.43928: UPnPAVRendererController::set_transport_state(232)/4288: INFO: No local playing item
43.43929: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Stopped
47.47485: RendererController::GetNearestResource(188)/4288: INFO: matching resource url to target ip 192.168.100.115: http://192.168.100.107:56923/content/7c1ceae8ca251af97272bd4a3e23c8e4.mp3
47.47542: RendererController::GetNearestResource(188)/4288: INFO: matching resource url to target ip 192.168.100.115: http://192.168.100.107:56923/content/6a8b310c4eb22e0b913d89985b69f5d0.mp3
47.47552: FbMediaServer::CheckAuthentication(401)/3204: INFO: received LAN request: http://192.168.100.107:56923/content/7c1ceae8ca251af97272bd4a3e23c8e4.mp3 (User-Agent: Network_Module/1.0 (HTR-4068))
47.47553: PLT_HttpHelper::ToLog(432)/3204: INFO: FbMediaServer::ProcessFileRequest
GET /content/7c1ceae8ca251af97272bd4a3e23c8e4.mp3 HTTP/1.1
Host: 192.168.100.107:56923
User-Agent: Network_Module/1.0 (HTR-4068)
Accept: */*
transferMode.dlna.org: Streaming
Content-Length: 0
opened 'file://\\fekidesktop\d$\.backup musiccd\musiccd061\QUEEN04\TRACK10.MP3' for streaming
48.48186: controller_window::set_track_info_string(1112)/4288: INFO: track info display:
48.48260: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
49.49340: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
50.50394: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
51.51446: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
52.52493: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
53.53543: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
54.54595: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
55.55645: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
56.56695: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
57.57745: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
58.58794: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
59.59846: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
60.60894: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Queen - Good Company | MP3 | 112 kbps | 44100 Hz
61.61807: UPnPAVRendererController::set_transport_state(232)/4288: INFO: No local playing item
61.61809: controller_window::set_track_info_string(1112)/4288: INFO: track info display: Stopped
63.63058: NPT_HttpClient::SendRequestOnce(1739)/4356: WARNING: NPT_CHECK failed, result=-20014 (NPT_ERROR_TIMEOUT) [(m_Connector->Connect(request.GetUrl(), *this, use_proxy?&proxy:0, http_1_1, connection))]
63.63059: PLT_CtrlPoint::ProcessGetDescriptionResponse(1373)/4356: FATAL: NPT_CHECK failed, result=-20014 (NPT_ERROR_TIMEOUT) [(res)]
63.63059: PLT_CtrlPoint::ProcessGetDescriptionResponse(1414)/4356: SEVERE: Bad Description response @ http://192.168.0.1:1900/igd.xml:
83.83555: PLT_CtrlPoint::InspectDevice(1269)/2980: INFO: Inspecting device "060b7353-fca6-4070-85f4-1fbfb9add62c" detected @ http://192.168.0.1:1900/igd.xml
88.88550: PLT_CtrlPoint::Subscribe(1588)/4288: INFO: Unsubscribing subscriber "uuid:9ab0c000-f668-11de-9976-6412572b7c8e" for service "urn:upnp-org:serviceId:RenderingControl" of device "HTR-4068 D0160F"
88.88555: NPT_HttpClient::SendRequestOnce(1739)/2248: WARNING: NPT_CHECK failed, result=-20021 (NPT_ERROR_CANCELLED) [(m_Connector->Connect(request.GetUrl(), *this, use_proxy?&proxy:0, http_1_1, connection))]
88.88555: PLT_CtrlPoint::ProcessGetDescriptionResponse(1373)/2248: FATAL: NPT_CHECK failed, result=-20021 (NPT_ERROR_CANCELLED) [(res)]
88.88555: PLT_CtrlPoint::ProcessGetDescriptionResponse(1414)/2248: SEVERE: Bad Description response @ http://192.168.0.1:1900/igd.xml:
88.88704: PLT_HttpListenTask::DoRun(491)/2776: WARNING: PLT_HttpListenTask exiting with -20021 (NPT_ERROR_CANCELLED)
88.88707: PLT_UPnP::Stop(191)/4288: INFO: Stopping UPnP...
88.88757: PLT_HttpListenTask::DoRun(491)/4612: WARNING: PLT_HttpListenTask exiting with -20021 (NPT_ERROR_CANCELLED)
88.88771: PLT_HttpServerSocketTask::Read(195)/2980: WARNING: NPT_CHECK failed, result=-20021 (NPT_ERROR_CANCELLED) [(res)]