[main (2) ] INFO - 0:00:00.80 - enableLogToFile: opened /storage/emulated/0/Download/com.bubblesoft.android.bubbleds2_log.txt [main (2) ] INFO - 0:00:00.83 - enableLogToFile: logging to file is enabled in 80 ms [main (2) ] INFO - 0:00:00.106 - system locale: en_GB [main (2) ] INFO - 0:00:00.106 - app locale: en_GB [main (2) ] INFO - 0:00:00.107 - os.arch: armv8l [main (2) ] INFO - 0:00:00.107 - API level: 30 [main (2) ] INFO - 0:00:00.108 - manufacturer: samsung [main (2) ] INFO - 0:00:00.108 - model: SM-A105FN [main (2) ] INFO - 0:00:00.108 - product: a10eea [main (2) ] INFO - 0:00:00.109 - fingerprint: samsung/a10eea/a10:11/RP1A.200720.012/A105FNXXS7CVD2:user/release-keys [main (2) ] INFO - 0:00:00.109 - debuggable: false [main (2) ] INFO - 0:00:00.109 - installed on external storage: false [main (2) ] INFO - 0:00:00.111 - touch supported: true [main (2) ] INFO - 0:00:00.113 - gestural navigation: false [main (2) ] INFO - 0:00:00.113 - large memory class: 512 [main (2) ] INFO - 0:00:00.114 - isSustainedPerformanceModeSupported: false [main (2) ] INFO - 0:00:00.115 - SUPPORTED_ABIS: armeabi-v7a,armeabi [main (2) ] INFO - 0:00:00.115 - app: version: 3.9, code: 57 [main (2) ] INFO - 0:00:00.122 - app standby bucket: ACTIVE [main (2) ] INFO - 0:00:00.123 - xposed running: false [main (2) ] INFO - 0:00:00.129 - isDevMode: false [main (2) ] INFO - 0:00:00.168 - app info: true false false false false true 0 [main (2) ] INFO - 0:00:00.176 - toast: WARNING: Logging to file is enabled [main (2) ] INFO - 0:00:00.235 - warning toast: Logging to file is enabled [main (2) ] INFO - 0:00:00.251 - watchdog: set inactivity delay to 0 mins [main (2) ] INFO - 0:00:00.274 - useScopedStorage: true [main (2) ] INFO - 0:00:00.286 - getLocalRendererRemoteControlVideoApp: no package name [main (2) ] INFO - 0:00:00.372 - isReadExternalStorageGranted: true [main (2) ] INFO - 0:00:00.373 - isWriteExternalStorageGranted: true [main (2) ] INFO - 0:00:00.373 - .n : App.onCreate() took: 378 ms [main (2) ] INFO - 0:00:00.374 - StrictMode (JB): LAX [main (2) ] INFO - 0:00:00.445 - onCreate(): Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.bubblesoft.android.bubbleds2/com.bubblesoft.android.bubbleupnp.MainTabActivity bnds=[530,106][694,327] }@ecb4f02:972 [main (2) ] INFO - 0:00:00.623 - .n : MainTabActivity.onCreate() took: 216 ms [main (2) ] INFO - 0:00:00.627 - onStart()@ecb4f02:972 [main (2) ] INFO - 0:00:00.630 - onPostCreate()@ecb4f02:972 [main (2) ] INFO - 0:00:00.630 - startup page index: 0 [main (2) ] INFO - 0:00:00.632 - onResume()@ecb4f02:972 [main (2) ] INFO - 0:00:00.672 - screen generalized size: normal [main (2) ] INFO - 0:00:00.673 - screen generalized density: 280 dpi [main (2) ] INFO - 0:00:00.673 - logical density factor: 1.750000 [main (2) ] INFO - 0:00:00.674 - screen size (pixels): 720x1382 [main (2) ] INFO - 0:00:00.674 - screen size (dp): 411x789 [main (2) ] INFO - 0:00:00.674 - screen size (inches): 2.677167x5.118784, diagonal: 5.776606 [main (2) ] INFO - 0:00:00.675 - screen size aspect ratio (h/w): 1.919444 [main (2) ] INFO - 0:00:00.675 - isTallActivity: true [main (2) ] INFO - 0:00:00.676 - isTallDevice: true [main (2) ] INFO - 0:00:00.676 - isInMultiWindowMode: false [main (2) ] INFO - 0:00:00.677 - xdpi: 268.941010 [main (2) ] INFO - 0:00:00.677 - ydpi: 269.985992 [main (2) ] INFO - 0:00:00.678 - landscape: false [main (2) ] INFO - 0:00:00.678 - isPhoneSize: true [main (2) ] INFO - 0:00:00.678 - smallest width (dp): 411 [main (2) ] INFO - 0:00:00.680 - onCreate [main (2) ] INFO - 0:00:00.708 - calling startForeground() [main (2) ] INFO - 0:00:00.722 - renderer chooser target: loaded 1 targets [AndroidUpnpService-DownloadManager (3420] INFO - 0:00:00.766 - waiting for download requests... [main (2) ] INFO - 0:00:00.767 - .d : default UPnP action timeout: 30s [main (2) ] INFO - 0:00:00.775 - .d : >>> Starting UPnP service... [main (2) ] INFO - 0:00:00.776 - .d : Using configuration: com.bubblesoft.android.bubbleupnp.AndroidUpnpService$v1 [main (2) ] INFO - 0:00:00.782 - .d : <<< UPnP service started successfully [main (2) ] INFO - 0:00:00.910 - starting Jetty... [main (2) ] INFO - 0:00:00.931 - external storage available: true, root directory: /storage/emulated/0, state: mounted, path: null [pool-10-thread-1 (34203) ] INFO - 0:00:00.934 - Album Artist column supported: false [main (2) ] INFO - 0:00:00.942 - excluded remote directories: [] [main (2) ] INFO - 0:00:00.952 - not starting Audio Cast servlet: The 'BubbleUPnP Audio Cast' Xposed module is not installed [main (2) ] INFO - 0:00:00.955 - jetty-7.x.y-SNAPSHOT [main (2) ] INFO - 0:00:00.968 - started {} [main (2) ] INFO - 0:00:00.971 - started {} [main (2) ] INFO - 0:00:00.972 - started {} [main (2) ] INFO - 0:00:00.974 - started {} [main (2) ] INFO - 0:00:00.976 - started {} [main (2) ] INFO - 0:00:00.977 - started {} [main (2) ] INFO - 0:00:00.978 - started {} [main (2) ] INFO - 0:00:00.979 - started {} [main (2) ] INFO - 0:00:00.992 - Started {} [main (2) ] INFO - 0:00:00.992 - started Jetty [main (2) ] WARNING - 0:00:00.996 - smb: failed to load: /data/user/0/com.bubblesoft.android.bubbleds2/files/smbshares: java.io.FileNotFoundException: /data/user/0/com.bubblesoft.android.bubbleds2/files/smbshares: open failed: ENOENT (No such file or directory) [main (2) ] INFO - 0:00:00.997 - smb: local inet address: /127.0.0.1 [main (2) ] INFO - 0:00:00.999 - added local media server to registry [main (2) ] INFO - 0:00:00.999 - created local media server [main (2) ] INFO - 0:00:01.0 - initBatterySavingMode: true [main (2) ] WARNING - 0:00:01.1 - .a : WiFi lock already released [main (2) ] INFO - 0:00:01.1 - discovery maintenance: started executor, interval: 120 seconds [main (2) ] INFO - 0:00:01.3 - .a : network type changed => WIFI [main (2) ] WARNING - 0:00:01.4 - .a : WiFi multicast lock already released [main (2) ] WARNING - 0:00:01.5 - .a : WiFi lock already released [main (2) ] INFO - 0:00:01.5 - .a : Creating Router: uo.b [main (2) ] INFO - 0:00:01.10 - .i : Created socket (for receiving TCP streams) on: /127.0.0.1:58646 [main (2) ] INFO - 0:00:01.11 - .i : Created socket (for receiving TCP streams) on: /192.168.0.14:58646 [main (2) ] INFO - 0:00:01.12 - .e : Creating wildcard socket (for receiving multicast datagrams) on port: 1900 [main (2) ] INFO - 0:00:01.15 - .e : Joining multicast group: /239.255.255.250:1900 on network interface: wlan0 [main (2) ] INFO - 0:00:01.16 - .e : Creating wildcard socket (for receiving multicast datagrams) on port: 1900 [main (2) ] INFO - 0:00:01.16 - .e : Joining multicast group: /239.255.255.250:1900 on network interface: lo [main (2) ] INFO - 0:00:01.17 - .a : Creating bound socket (for datagram input/output) on: /127.0.0.1 [main (2) ] INFO - 0:00:01.18 - .a : Creating bound socket (for datagram input/output) on: /192.168.0.14 [main (2) ] INFO - 0:00:01.19 - .a : enabled router on network type change (new network: WIFI) [main (2) ] INFO - 0:00:01.20 - inspecting new device: BubbleUPnP Media Server (SM-A105FN) [main (2) ] INFO - 0:00:01.21 - No product service found [main (2) ] INFO - 0:00:01.25 - BubbleUPnP Media Server (SM-A105FN): constructed successfully [main (2) ] INFO - 0:00:01.26 - active media server: Local and Cloud [main (2) ] INFO - 0:00:01.30 - found Media Server: BubbleUPnP Media Server (SM-A105FN) [MediaServer-FetchSupportedMimeType (3422] INFO - 0:00:01.36 - BubbleUPnP Media Server (SM-A105FN) supported mime-types: [audio/mpeg, audio/mp4, audio/mp4, audio/wav, audio/wave, audio/x-wav, audio/mpeg, audio/x-mpeg, audio/mp1, audio/aac, audio/flac, audio/x-flac, audio/m4a, audio/mp4, audio/x-m4a, audio/x-scpls, audio/vorbis, audio/ogg, audio/x-ogg, audio/x-ms-wma, application/x-ms-wma, audio/wma, application/wma, image/png, image/png, image/jpeg, image/jpeg, image/jpeg, image/jpeg, image/png, image/jpeg, image/bmp, image/gif, image/jpg, image/mpo, video/vnd.dlna.mpeg-tts, video/mpeg2, video/mpeg, application/vnd.apple.mpegurl, application/x-mpegurl, video/x-m4v, video/mp4v-es, video/mp4, video/mp2t, video/quicktime, video/x-ms-wmv, video/x-ms-asf, video/x-ms-video, video/divx, video/vnd.divx, video/x-divx, video/x-ms-avi, video/avi, video/x-msvideo, video/x-mkv, video/mkv, video/x-matroska, video/ogg, video/3gpp, video/webm, application/flv, video/x-flv, video/flv, video/wtv, application/vnd.rn-realmedia-vbr, video/vnd.rn-realvideo, application/vnd.rn-realmedia] [main (2) ] INFO - 0:00:01.42 - .c : Manufacturer: Bubblesoft Manufacturer URL: http://forum.xda-developers.com/showthread.php?t=1118891 Model name: BubbleUPnP Media Server Model description: BubbleUPnP Media Server Model number: 3.9 UDN: 8fd69fb8-17c8-42cf-b8df-1633c1e16569 [main (2) ] INFO - 0:00:01.44 - Started {} [main (2) ] INFO - 0:00:01.45 - started Jetty connector on 192.168.0.14:57745 [main (2) ] INFO - 0:00:01.46 - smb: local inet address: /192.168.0.14 [main (2) ] SEVERE - 0:00:01.46 - watchdog: device search++: 1 [main (2) ] INFO - 0:00:01.46 - wake lock acquired [main (2) ] INFO - 0:00:01.48 - .a : WiFi lock acquired [main (2) ] INFO - 0:00:01.67 - performDeviceSearch: searching... [main (2) ] INFO - 0:00:01.72 - .g : Display name: wlan0 [main (2) ] INFO - 0:00:01.73 - .g : Name: wlan0 [main (2) ] INFO - 0:00:01.73 - .g : InetAddress: /fe80::243c:6eff:fe09:1118%wlan0 [main (2) ] INFO - 0:00:01.74 - .g : InetAddress: /192.168.0.14 [main (2) ] INFO - 0:00:01.74 - .g : Interface Address [main (2) ] INFO - 0:00:01.75 - .g : Address: /fe80::243c:6eff:fe09:1118%wlan0 [main (2) ] INFO - 0:00:01.76 - .g : Broadcast: null [main (2) ] INFO - 0:00:01.76 - .g : Prefix length: 64 [main (2) ] INFO - 0:00:01.77 - .g : Interface Address [main (2) ] INFO - 0:00:01.77 - .g : Address: /192.168.0.14 [main (2) ] INFO - 0:00:01.78 - .g : Broadcast: /192.168.0.255 [main (2) ] INFO - 0:00:01.79 - .g : Prefix length: 24 [main (2) ] INFO - 0:00:01.82 - .g : Up? true [main (2) ] INFO - 0:00:01.83 - .g : Loopback? false [main (2) ] INFO - 0:00:01.83 - .g : PointToPoint? false [main (2) ] INFO - 0:00:01.84 - .g : Supports multicast? true [main (2) ] INFO - 0:00:01.85 - .g : Virtual? false [main (2) ] INFO - 0:00:01.87 - .g : Hardware address: null [main (2) ] INFO - 0:00:01.89 - .g : MTU: 1500 [main (2) ] INFO - 0:00:01.90 - .g : Display name: lo [main (2) ] INFO - 0:00:01.92 - .g : Name: lo [main (2) ] INFO - 0:00:01.93 - .g : InetAddress: /::1 [main (2) ] INFO - 0:00:01.93 - .g : InetAddress: /127.0.0.1 [main (2) ] INFO - 0:00:01.93 - .g : Interface Address [main (2) ] INFO - 0:00:01.94 - .g : Address: /::1 [main (2) ] INFO - 0:00:01.94 - .g : Broadcast: null [main (2) ] INFO - 0:00:01.94 - .g : Prefix length: 128 [main (2) ] INFO - 0:00:01.94 - .g : Interface Address [main (2) ] INFO - 0:00:01.94 - .g : Address: /127.0.0.1 [main (2) ] INFO - 0:00:01.95 - .g : Broadcast: null [main (2) ] INFO - 0:00:01.98 - .g : Prefix length: 8 [main (2) ] INFO - 0:00:01.99 - .g : Up? true [main (2) ] INFO - 0:00:01.102 - .g : Loopback? true [main (2) ] INFO - 0:00:01.103 - .g : PointToPoint? false [main (2) ] INFO - 0:00:01.104 - .g : Supports multicast? false [main (2) ] INFO - 0:00:01.104 - .g : Virtual? false [main (2) ] INFO - 0:00:01.106 - .g : Hardware address: null [main (2) ] INFO - 0:00:01.107 - .g : MTU: 65536 [main (2) ] INFO - 0:00:01.108 - billing: calling startConnection... [main (2) ] INFO - 0:00:01.115 - set scrobbler: 0 [main (2) ] INFO - 0:00:01.116 - Trakt resume: cleared progressed entities [main (2) ] INFO - 0:00:01.118 - registered receiver for 'mute on phone call' [cling-24 (34243) ] INFO - 0:00:01.120 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.13:5001/description/fetch [cling-20 (34239) ] INFO - 0:00:01.120 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.12:8200/rootDesc.xml [cling-26 (34245) ] INFO - 0:00:01.123 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.12:8080/upnpd/3dfb3dc65d.xml [main (2) ] INFO - 0:00:01.123 - isGMSAvailable: true, hasTunInterface: false, isGoogleCastDiscoveryAlwaysStarted: false [AndroidUpnpService-LoadResumePositions (] INFO - 0:00:01.132 - loaded 2 resume positions [main (2) ] INFO - 0:00:01.155 - .n : AndroidUpnpService.onCreate() took: 475 ms [main (2) ] INFO - 0:00:01.182 - setStatusbarInsets: 30dp [main (2) ] INFO - 0:00:01.251 - setSeekBarDetailsText: [main (2) ] INFO - 0:00:01.252 - .n : NowPlayingFragment.onCreateView() took: 61 ms [main (2) ] INFO - 0:00:01.288 - .n : PlaylistFragment.onCreateView() took: 31 ms [cling-21 (34240) ] INFO - 0:00:01.324 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml [cling-27 (34247) ] INFO - 0:00:01.330 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.16:8080/dd.xml [main (2) ] INFO - 0:00:01.374 - .n : LibraryFragment.onCreateView() took: 85 ms [main (2) ] INFO - 0:00:01.410 - setStatusbarInsets: 30dp [main (2) ] INFO - 0:00:01.436 - setStatusbarInsets: 30dp [main (2) ] INFO - 0:00:01.649 - installed security provider [main (2) ] INFO - 0:00:01.649 - .a : CONNECTIVITY_ACTION [main (2) ] INFO - 0:00:01.650 - .a : EXTRA_NO_CONNECTIVITY: false [main (2) ] INFO - 0:00:01.650 - .a : EXTRA_REASON: null [main (2) ] INFO - 0:00:01.650 - .a : EXTRA_IS_FAILOVER: false [main (2) ] INFO - 0:00:01.651 - .a : EXTRA_NETWORK_INFO: [type: WIFI[], state: CONNECTED/CONNECTED, reason: (unspecified), extra: , failover: false, available: true, roaming: false] [main (2) ] INFO - 0:00:01.651 - .a : EXTRA_OTHER_NETWORK_INFO: none [main (2) ] INFO - 0:00:01.651 - .a : EXTRA_EXTRA_INFO: [main (2) ] INFO - 0:00:01.653 - .a : No network change...ignoring event [main (2) ] INFO - 0:00:01.653 - battery EXTRA_PLUGGED: 0 [main (2) ] INFO - 0:00:01.654 - battery EXTRA_STATUS: 3 [main (2) ] INFO - 0:00:01.654 - using battery changed: null => true [main (2) ] INFO - 0:00:01.656 - added listener: 1 listeners [main (2) ] INFO - 0:00:01.663 - setSeekBarDetailsText: [main (2) ] INFO - 0:00:01.673 - onNetworkSearchStarted [main (2) ] INFO - 0:00:01.726 - added listener: 2 listeners [main (2) ] INFO - 0:00:01.729 - created mini-player (context: com.bubblesoft.android.bubbleupnp.MainTabActivity@ecb4f02) [main (2) ] INFO - 0:00:01.729 - added listener: 3 listeners [main (2) ] INFO - 0:00:01.731 - active renderer: none [main (2) ] INFO - 0:00:01.739 - Qobuz: using https://www.qobuz.com/api.json/0.2 [main (2) ] INFO - 0:00:01.740 - Qobuz: username: set [main (2) ] INFO - 0:00:01.742 - saveMediaServerState: Local and Cloud [main (2) ] INFO - 0:00:01.747 - saveMediaServerState: Local and Cloud [main (2) ] INFO - 0:00:01.748 - positionStack: [] [main (2) ] INFO - 0:00:01.748 - itemList: Bundle[{firstVisiblePosition=0}] [main (2) ] INFO - 0:00:01.749 - containerPath: [] [main (2) ] INFO - 0:00:01.759 - added listener: 4 listeners [AsyncTask #1 (34198) ] INFO - 0:00:01.759 - BrowseTask.doInBackground(): enter [main (2) ] INFO - 0:00:01.760 - active renderer: none [AsyncTask #1 (34198) ] INFO - 0:00:01.761 - BrowseTask.doInBackground(): loading container 'Local and Cloud' [main (2) ] INFO - 0:00:01.761 - added listener: 5 listeners [main (2) ] INFO - 0:00:01.761 - active renderer: none [main (2) ] INFO - 0:00:01.762 - inspecting new device: Universal Media Server [main (2) ] INFO - 0:00:01.762 - No product service found [cling-12 (Browse) (34231) ] INFO - 0:00:01.762 - ObjectID: 0 [main (2) ] INFO - 0:00:01.763 - Universal Media Server: constructed successfully [cling-12 (Browse) (34231) ] INFO - 0:00:01.764 - BrowseFlag: BrowseDirectChildren [cling-12 (Browse) (34231) ] INFO - 0:00:01.764 - StartingIndex: 0 [main (2) ] INFO - 0:00:01.765 - found Media Server: Universal Media Server [cling-12 (Browse) (34231) ] INFO - 0:00:01.765 - RequestedCount: 0 [cling-12 (Browse) (34231) ] INFO - 0:00:01.766 - Filter: * [main (2) ] INFO - 0:00:01.766 - .c : Manufacturer: UMS Manufacturer URL: http://www.universalmediaserver.com Model name: UMS Model description: UPnP/AV 1.0 Compliant Media Server Model number: 01 Model URL: http://www.universalmediaserver.com Presentation URL: http://192.168.0.13:5001/console/index.html UDN: 0ff1c55e-c20d-457d-838b-d8ba01447b9a [cling-12 (Browse) (34231) ] INFO - 0:00:01.776 - .n : sort 0 items took: 0 ms [cling-12 (Browse) (34231) ] WARNING - 0:00:01.779 - webdav: failed to load: /data/user/0/com.bubblesoft.android.bubbleds2/files/webdavservers: java.io.FileNotFoundException: /data/user/0/com.bubblesoft.android.bubbleds2/files/webdavservers: open failed: ENOENT (No such file or directory) [MediaServer-FetchSupportedMimeType (3426] INFO - 0:00:01.786 - Universal Media Server supported mime-types: [image/jpeg, image/jpeg, image/jpeg, image/jpeg, image/jpeg, image/png, image/png, image/gif, audio/mpeg, audio/L16, video/mpeg, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/x-mp2t-mphl-188, *, video/*, audio/*, image/*] [main (2) ] INFO - 0:00:01.834 - inspecting new device: NAS1F8CF0 [main (2) ] INFO - 0:00:01.835 - No product service found [main (2) ] INFO - 0:00:01.838 - NAS1F8CF0: constructed successfully [main (2) ] INFO - 0:00:01.840 - found Media Server: NAS1F8CF0 [main (2) ] INFO - 0:00:01.841 - .c : Manufacturer: Microsoft Manufacturer URL: http://www.qnap.com Model name: Windows Media Player Sharing Model description: QNAPDLNA on TurboNAS Model number: 3.0 Model URL: http://www.qnap.com Presentation URL: http://192.168.0.12:8080/ Serial number: 12345678 UDN: 4d696e69-444c-164e-9d41-245ebe1f8cf0 [cling-12 (Browse) (34231) ] INFO - 0:00:01.852 - browsed 18/18 items in 86ms [main (2) ] INFO - 0:00:01.872 - battery EXTRA_PLUGGED: 0 [main (2) ] INFO - 0:00:01.872 - battery EXTRA_STATUS: 3 [main (2) ] INFO - 0:00:01.873 - using battery changed: true => null [main (2) ] INFO - 0:00:01.874 - inspecting new device: Roberts Stream 94i 0022611668f8 [main (2) ] INFO - 0:00:01.875 - No product service found [main (2) ] INFO - 0:00:01.875 - Roberts Stream 94i 0022611668f8: not a Media Server: java.lang.Exception: Roberts Stream 94i 0022611668f8: cannot find service ContentDirectory [main (2) ] INFO - 0:00:01.876 - inspecting new device: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:01.877 - found OpenHome device [main (2) ] INFO - 0:00:01.877 - Found Product service [main (2) ] INFO - 0:00:01.878 - Found Radio service [main (2) ] INFO - 0:00:01.878 - Found Playlist service [main (2) ] INFO - 0:00:01.879 - Found Time service [main (2) ] INFO - 0:00:01.879 - Found Volume service [cling-21 (34240) ] INFO - 0:00:02.54 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.13:49505/298a34700d49fcf4b729b4ab26975961/Upnp/device.xml [cling-43 (34268) ] INFO - 0:00:02.213 - .f : Sending device descriptor retrieval message: (c) GET http://192.168.0.12:9791/3a664842-86fb-4524-8e9f-0bc0d5572b07/Upnp/device.xml [main (2) ] INFO - 0:00:02.556 - Found Info service [main (2) ] INFO - 0:00:02.557 - Found Receiver service [main (2) ] INFO - 0:00:02.558 - Found Credentials service [main (2) ] INFO - 0:00:02.558 - Found Volkano service [MediaServer-FetchSupportedMimeType (3426] INFO - 0:00:02.577 - NAS1F8CF0 supported mime-types: [image/jpeg, image/jpeg, image/jpeg, image/jpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mp4, video/mp4, video/mp4, video/mp4, video/mp4, video/mp4, video/mp4, video/mp4, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mpeg, video/mp4, video/mp4, video/mp4, video/mp4, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/mpeg, video/vnd.dlna.mpeg-tts, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/x-ms-wmv, video/3gpp, video/3gpp, audio/mpeg, audio/asf, audio/asf, audio/asf, audio/asf, audio/asf, audio/x-ms-wma, audio/x-ms-wma, audio/x-ms-wma, audio/x-ms-wma, audio/x-ms-wma, audio/mp4, audio/mp4, audio/vnd.dlna.adts, audio/vnd.dlna.adts, audio/vnd.dlna.adts, audio/L16, audio/L16, audio/L16, audio/L16, audio/wav, audio/x-wav, image/jpeg, video/avi, video/divx, video/x-matroska, video/mpeg, video/mp4, video/x-ms-wmv, video/x-msvideo, video/x-flv, video/x-tivo-mpeg, video/quicktime, audio/mp4, audio/x-wav, audio/x-flac, application/ogg] [main (2) ] INFO - 0:00:02.885 - Majik DSM 530:Majik DSM: volume range: 0..100 [main (2) ] INFO - 0:00:02.888 - Majik DSM 530:Majik DSM: time task polling interval: 900ms [main (2) ] INFO - 0:00:02.889 - added renderer: Majik DSM 530:Majik DSM (ip: 192.168.0.18) [main (2) ] INFO - 0:00:02.891 - Qobuz: username: set [main (2) ] INFO - 0:00:02.892 - fireRootContentChanged [main (2) ] INFO - 0:00:02.893 - BrowseTask::cancel(): cancelling... [AsyncTask #1 (34198) ] INFO - 0:00:02.899 - interrupted while waiting for progress completion [main (2) ] INFO - 0:00:02.899 - BrowseTask::cancel(): waiting for cancellation to complete... [AsyncTask #1 (34198) ] INFO - 0:00:02.900 - BrowseTask.doInBackground(): browse interrupted [AsyncTask #1 (34198) ] WARNING - 0:00:02.901 - browse exception: java.lang.InterruptedException [AsyncTask #1 (34198) ] WARNING - 0:00:02.908 - java.lang.InterruptedException at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1024) at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1334) at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:232) at com.bubblesoft.android.bubbleupnp.LibraryFragment$e1$a.a(SourceFile:3) at com.bubblesoft.upnp.mediaserver.MediaServer.d(SourceFile:11) at com.bubblesoft.android.bubbleupnp.LibraryFragment$e1.e(SourceFile:12) at com.bubblesoft.android.bubbleupnp.LibraryFragment$e1.g(SourceFile:6) at com.bubblesoft.android.bubbleupnp.LibraryFragment$e1.doInBackground(SourceFile:1) at android.os.AsyncTask$3.call(AsyncTask.java:394) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641) at java.lang.Thread.run(Thread.java:923) [main (2) ] INFO - 0:00:02.910 - BrowseTask::cancel(): cancellation complete [main (2) ] INFO - 0:00:02.912 - saveMediaServerState: Local and Cloud [main (2) ] INFO - 0:00:02.912 - positionStack: [] [main (2) ] INFO - 0:00:02.913 - itemList: Bundle[{firstVisiblePosition=0}] [main (2) ] INFO - 0:00:02.914 - containerPath: [] [main (2) ] INFO - 0:00:02.920 - active renderer: Majik DSM 530:Majik DSM [AsyncTask #1 (34198) ] INFO - 0:00:02.920 - BrowseTask.doInBackground(): enter [main (2) ] INFO - 0:00:02.921 - supports video: false [AsyncTask #1 (34198) ] INFO - 0:00:02.921 - BrowseTask.doInBackground(): loading container 'Local and Cloud' [main (2) ] INFO - 0:00:02.922 - OH token id: null [main (2) ] INFO - 0:00:02.923 - renderer Majik DSM 530:Majik DSM: control volume with Majik DSM 530:Majik DSM [cling-28 (Browse) (34248) ] INFO - 0:00:02.934 - ObjectID: 0 [cling-28 (Browse) (34248) ] INFO - 0:00:02.936 - BrowseFlag: BrowseDirectChildren [cling-28 (Browse) (34248) ] INFO - 0:00:02.936 - StartingIndex: 0 [cling-28 (Browse) (34248) ] INFO - 0:00:02.937 - RequestedCount: 0 [cling-28 (Browse) (34248) ] INFO - 0:00:02.938 - Filter: * [cling-28 (Browse) (34248) ] INFO - 0:00:02.947 - .n : sort 0 items took: 1 ms [main (2) ] INFO - 0:00:02.952 - onSourceChange: Analog [main (2) ] INFO - 0:00:02.954 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Product [main (2) ] INFO - 0:00:02.955 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Playlist [main (2) ] INFO - 0:00:02.956 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Radio [main (2) ] INFO - 0:00:02.957 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Info [main (2) ] INFO - 0:00:02.958 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Receiver [main (2) ] INFO - 0:00:02.959 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Credentials [main (2) ] INFO - 0:00:02.961 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Volume [main (2) ] INFO - 0:00:02.961 - source changed: Analog [main (2) ] INFO - 0:00:02.962 - selected item changed: TV [main (2) ] INFO - 0:00:02.986 - image file cache is enabled: /storage/emulated/0/Android/data/com.bubblesoft.android.bubbleds2/cache/images [main (2) ] INFO - 0:00:02.987 - enabled image file cache [cling-47 (34272) ] INFO - 0:00:03.2 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-343 on service (n) ServiceId: urn:av-openhome-org:serviceId:Radio of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [cling-28 (Browse) (34248) ] INFO - 0:00:03.21 - browsed 18/18 items in 83ms [cling-47 (34272) ] INFO - 0:00:03.56 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-344 on service (n) ServiceId: urn:av-openhome-org:serviceId:Info of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [main (2) ] INFO - 0:00:03.196 - isCustomNotificationExitButtonSupported: true [cling-37 (34259) ] INFO - 0:00:03.206 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-345 on service (n) ServiceId: urn:av-openhome-org:serviceId:Credentials of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [cling-37 (34259) ] INFO - 0:00:03.255 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-346 on service (n) ServiceId: urn:av-openhome-org:serviceId:Receiver of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [cling-33 (34255) ] INFO - 0:00:03.279 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-347 on service (n) ServiceId: urn:av-openhome-org:serviceId:Playlist of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [cling-26 (34245) ] WARNING - 0:00:03.514 - .f : Service descriptor retrieval failed: http://192.168.0.12:8080/upnpd/ConnectionManager.xml, 404 Not Found [cling-18 (34237) ] INFO - 0:00:03.514 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-348 on service (n) ServiceId: urn:av-openhome-org:serviceId:Product of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [cling-12 (34231) ] INFO - 0:00:03.543 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-349 on service (n) ServiceId: urn:av-openhome-org:serviceId:Volume of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [main (2) ] INFO - 0:00:03.680 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [main (2) ] INFO - 0:00:03.943 - VolumeProvider: ignoring: hardware volume keys disabled in settings [main (2) ] INFO - 0:00:03.944 - renderer chooser target: added target 'Majik DSM 530:Majik DSM' [main (2) ] INFO - 0:00:03.956 - renderer chooser target: saved 1 targets in 10 ms [main (2) ] INFO - 0:00:04.3 - onNetworkSearchEnded [main (2) ] INFO - 0:00:04.45 - active renderer: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.46 - source changed: Analog [main (2) ] INFO - 0:00:04.47 - selected item changed: TV [main (2) ] INFO - 0:00:04.59 - setSeekBarDetailsText: [main (2) ] INFO - 0:00:04.61 - active renderer: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.63 - addListener (context: com.bubblesoft.android.bubbleupnp.MainTabActivity@ecb4f02) [main (2) ] INFO - 0:00:04.96 - active renderer: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.97 - active renderer: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.102 - found renderer: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.105 - .c : Manufacturer: Linn Products Ltd Model name: Majik DSM Presentation URL: http://192.168.0.18:80/Ds/index.html UDN: 4c494e4e-0026-0f22-21c3-01444235013f [main (2) ] INFO - 0:00:04.106 - Majik DSM 530:Majik DSM: not a Media Server: java.lang.Exception: Majik DSM 530:Majik DSM: cannot find service ContentDirectory [main (2) ] INFO - 0:00:04.107 - Found Sender service [main (2) ] INFO - 0:00:04.109 - started subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Sender [main (2) ] INFO - 0:00:04.109 - added OpenHome Sender: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.110 - found OpenHome Sender: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:04.111 - .c : Manufacturer: Linn Products Ltd Model name: Majik DSM Presentation URL: http://192.168.0.18:80/Ds/index.html UDN: 4c494e4e-0026-0f22-21c3-01444235013f [main (2) ] INFO - 0:00:04.210 - inspecting new device: Linn Kazoo Server [Thiscomputer-6] [main (2) ] INFO - 0:00:04.211 - No product service found [main (2) ] INFO - 0:00:04.213 - Linn Kazoo Server [Thiscomputer-6]: constructed successfully [main (2) ] INFO - 0:00:04.215 - found Media Server: Linn Kazoo Server [Thiscomputer-6] [main (2) ] INFO - 0:00:04.216 - .c : Manufacturer: Linn Manufacturer URL: https://www.linn.co.uk Model name: Kazoo Server Model URL: https://www.linn.co.uk/software Presentation URL: http://192.168.0.13:4000/res/linn.co.uk.kazooserver/index.html UDN: 298a34700d49fcf4b729b4ab26975961 [main (2) ] INFO - 0:00:04.218 - inspecting new device: MinimServer[NAS1F8CF0] [main (2) ] INFO - 0:00:04.219 - No product service found [main (2) ] INFO - 0:00:04.221 - MinimServer[NAS1F8CF0]: constructed successfully [main (2) ] INFO - 0:00:04.223 - found Media Server: MinimServer[NAS1F8CF0] [main (2) ] INFO - 0:00:04.224 - .c : Manufacturer: minimserver.com Model name: MinimServer Presentation URL: http://192.168.0.12:9790/ UDN: 3a664842-86fb-4524-8e9f-0bc0d5572b07 [main (2) ] INFO - 0:00:04.244 - .n : sort 0 items took: 0 ms [main (2) ] INFO - 0:00:04.246 - .n : sort 0 items took: 1 ms [main (2) ] INFO - 0:00:04.249 - loaded file 8fd69fb8-17c8-42cf-b8df-1633c1e16569-Bookmarks ok: 0 items, 3 ms [main (2) ] INFO - 0:00:04.250 - .n : sort 0 items took: 0 ms [main (2) ] INFO - 0:00:04.266 - .n : sort 7 items took: 0 ms [main (2) ] INFO - 0:00:04.272 - .n : sort 7 items took: 1 ms [AsyncTask #1 (34198) ] INFO - 0:00:04.276 - finished browsing: got 18 objects [AsyncTask #1 (34198) ] INFO - 0:00:04.277 - BrowseTask.doInBackground(): no item [main (2) ] INFO - 0:00:04.285 - setSeekBarDetailsText: PCM • 96 kHz • 24 bits [cling-49 (34274) ] INFO - 0:00:04.291 - Established subscription uuid:4c494e4e-0026-0f22-21c3-01444235013f-350 on service (n) ServiceId: urn:av-openhome-org:serviceId:Sender of device (l) Identity: (RemoteDeviceIdentity) UDN: uuid:4c494e4e-0026-0f22-21c3-01444235013f, Descriptor: http://192.168.0.18:55178/4c494e4e-0026-0f22-21c3-01444235013f/Upnp/device.xml, Root: true [main (2) ] INFO - 0:00:04.446 - .b : selected item: 89.1 | BBC Radio 2 (Adult Hits) [main (2) ] INFO - 0:00:04.447 - Credentials: supported ids: [qobuz.com] [main (2) ] INFO - 0:00:04.472 - CredentialsSubscriptionCallback.getAction(qobuz.com): username: set, password: set, enabled: true, status: null, data: 140168716 [AsyncTask #1 (34288) ] INFO - 0:00:04.475 - qobuz.com: GetCredentialsSessionTask: loginAction() [main (2) ] WARNING - 0:00:04.478 - .b : couldn't find item trackId = 696 [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:04.479 - reading 331 new items to insert: [651, 339, 340, 341, 343, 344, 345, 346, 347, 349, 350, 351, 353, 354, 355, 356, 359, 360, 361, 362, 363, 364, 365, 367, 368, 369, 370, 371, 372, 374, 375, 376, 378, 379, 380, 381, 382, 383, 384, 385, 386, 387, 388, 389, 390, 391, 392, 394, 395, 396, 397, 400, 401, 403, 404, 405, 406, 407, 408, 409, 410, 412, 413, 415, 416, 417, 419, 420, 421, 422, 423, 424, 425, 426, 428, 429, 430, 431, 432, 433, 434, 435, 436, 437, 438, 439, 440, 441, 442, 443, 445, 446, 447, 450, 451, 452, 453, 454, 455, 456, 458, 460, 461, 462, 463, 464, 465, 466, 467, 468, 469, 470, 471, 472, 473, 474, 475, 476, 477, 478, 479, 480, 482, 483, 485, 486, 487, 488, 489, 491, 492, 493, 494, 495, 496, 497, 498, 499, 500, 501, 502, 503, 504, 505, 506, 507, 508, 509, 510, 511, 512, 513, 514, 515, 516, 517, 518, 519, 520, 521, 522, 523, 524, 525, 526, 527, 528, 529, 530, 531, 532, 533, 534, 535, 536, 537, 538, 539, 540, 541, 542, 543, 544, 545, 546, 547, 548, 549, 550, 551, 552, 553, 554, 555, 556, 557, 558, 559, 560, 561, 562, 563, 564, 565, 567, 568, 569, 570, 571, 573, 575, 576, 577, 578, 579, 580, 581, 582, 583, 584, 586, 587, 588, 589, 590, 591, 593, 594, 595, 596, 597, 598, 599, 600, 601, 602, 603, 604, 605, 606, 608, 609, 611, 612, 613, 614, 615, 616, 617, 618, 620, 621, 622, 623, 624, 625, 627, 628, 629, 631, 632, 634, 635, 636, 637, 638, 639, 641, 642, 643, 644, 645, 646, 647, 648, 649, 650, 654, 655, 656, 657, 658, 659, 660, 661, 662, 663, 664, 665, 666, 667, 668, 669, 670, 671, 672, 673, 674, 675, 676, 677, 678, 679, 680, 681, 682, 683, 684, 685, 686, 687, 688, 689, 690, 691, 692, 693, 694, 695, 696, 697, 698, 699, 700, 701, 702, 703, 704, 705, 706, 707] [main (2) ] INFO - 0:00:04.480 - onStandbyChange: standby=false, ignore=true [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:04.481 - getting [0..50[ [main (2) ] INFO - 0:00:04.481 - onStandbyChange: standby=false, ignore=true [main (2) ] INFO - 0:00:04.482 - onStandbyChange: standby=false, ignore=true [main (2) ] INFO - 0:00:04.483 - onStandbyChange: standby=false, ignore=true [main (2) ] INFO - 0:00:04.484 - Majik DSM 530:Majik DSM: AbstractRenderer.onVolumeChange: 55 [main (2) ] INFO - 0:00:04.508 - Majik DSM 530:Majik DSM: MainFragmentBase.onVolumeChange: 55 [main (2) ] INFO - 0:00:04.511 - setSeekBarDetailsText: PCM • 96 kHz • 24 bits [main (2) ] INFO - 0:00:04.512 - Majik DSM 530:Majik DSM: MainFragmentBase.onVolumeChange: 55 [main (2) ] INFO - 0:00:04.513 - Majik DSM 530:Majik DSM: MainFragmentBase.onVolumeChange: 55 [main (2) ] INFO - 0:00:04.514 - Majik DSM 530:Majik DSM: MainFragmentBase.onVolumeChange: 55 [main (2) ] INFO - 0:00:04.515 - Majik DSM 530:Majik DSM: AbstractRenderer.onMuteChange: false [main (2) ] INFO - 0:00:04.537 - setSeekBarDetailsText: PCM • 96 kHz • 24 bits [main (2) ] INFO - 0:00:04.975 - Qobuz: Linn status: null [main (2) ] INFO - 0:00:04.976 - Qobuz: username: set [main (2) ] INFO - 0:00:04.976 - Qobuz: user auth token: PoJc-gGj_V1iOE2QgKSkRGeVzbOJsYKdSAa0wOK-ZCqkk7xBY9kf7qjIZ5e_0A9vbzn5uq6iospTgBA1Y5H1jA [PlayBillingLibrary-1 (34285) ] INFO - 0:00:05.12 - billing: onBillingSetupFinished (result: OK) [PlayBillingLibrary-2 (34291) ] INFO - 0:00:05.25 - billing: onQueryPurchasesResponse (result: OK) [main (2) ] INFO - 0:00:05.113 - billing: purchase state: PURCHASED [main (2) ] INFO - 0:00:05.140 - billing: purchase already aknowledged [main (2) ] INFO - 0:00:05.176 - billing: shutdown [MediaServer-FetchSupportedMimeType (3428] INFO - 0:00:05.281 - MinimServer[NAS1F8CF0] supported mime-types: [*] [MediaServer-FetchSupportedMimeType (3428] INFO - 0:00:05.281 - Linn Kazoo Server [Thiscomputer-6] supported mime-types: [*] [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:05.471 - getting [50..100[ [cling-26 (34245) ] WARNING - 0:00:05.706 - .f : Service descriptor retrieval failed: http://192.168.0.12:8080/upnpd/ContentDirectory.xml, 404 Not Found [main (2) ] INFO - 0:00:05.710 - inspecting new device: NAS1F8CF0 [main (2) ] INFO - 0:00:05.711 - No product service found [main (2) ] INFO - 0:00:05.713 - NAS1F8CF0: not a Media Server: java.lang.Exception: NAS1F8CF0: cannot find service ContentDirectory [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:06.562 - getting [100..150[ [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:07.274 - getting [150..200[ [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:07.989 - getting [200..250[ [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:08.746 - getting [250..300[ [AbstractPlaylistService-FirstIdArray (34] INFO - 0:00:09.490 - getting [300..331[ [main (2) ] INFO - 0:00:10.142 - .b : selected item: You're My Thrill [main (2) ] INFO - 0:00:31.73 - performDeviceSearch: search done [main (2) ] SEVERE - 0:00:31.76 - watchdog: device search--: 0 [main (2) ] INFO - 0:00:31.78 - wake lock released [main (2) ] INFO - 0:00:31.83 - .a : WiFi lock released [main (2) ] WARNING - 0:00:31.99 - .a : WiFi multicast lock already released [main (2) ] INFO - 0:00:31.104 - .a : disabled discovery [main (2) ] INFO - 0:00:32.347 - playItem: main thread [PlaybackActions (34294) ] INFO - 0:00:32.349 - playItem: worker thread enter [PlaybackAction:playItem (34294) ] INFO - 0:00:32.438 - .n : playItem worker thread took: 88 ms [cling-6 (34224) ] WARNING - 0:00:32.719 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Product/event/cb SEQUENCE: 1, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-335 [cling-5 (34223) ] WARNING - 0:00:32.724 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 1, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [main (2) ] INFO - 0:00:32.726 - onSourceChange: Playlist [main (2) ] INFO - 0:00:32.726 - source changed: Playlist [main (2) ] INFO - 0:00:32.727 - selected item changed: You're My Thrill [cling-6 (34224) ] WARNING - 0:00:32.786 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 2, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [main (2) ] INFO - 0:00:32.787 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [qtp89477530-34251 (34251) ] INFO - 0:00:32.840 - .c : REQUEST HEADERS [qtp89477530-34251 (34251) ] INFO - 0:00:32.845 - .c : GET /proxy/tidal/A20C0C4A662775EA146E4F463D86DAB1.flac HTTP/1.1 {} 192.168.0.18 1663661673254 [main (2) ] INFO - 0:00:32.848 - source changed: Playlist [main (2) ] INFO - 0:00:32.849 - selected item changed: You're My Thrill [qtp89477530-34251 (34251) ] INFO - 0:00:32.847 - .c : Icy-MetaData: 1 [qtp89477530-34251 (34251) ] INFO - 0:00:32.850 - .c : Connection: close [qtp89477530-34251 (34251) ] INFO - 0:00:32.850 - .c : Range: bytes=0- [main (2) ] WARNING - 0:00:32.853 - failed to find or create dir: Various Interprets - Both Sides Now [qtp89477530-34251 (34251) ] INFO - 0:00:32.854 - .c : User-Agent: LWIP/1.4.2-linn [main (2) ] INFO - 0:00:32.855 - setSeekBarDetailsText: PCM • 96 kHz • 24 bits [qtp89477530-34251 (34251) ] INFO - 0:00:32.855 - .c : Host: 192.168.0.14:57745 [qtp89477530-34251 (34251) ] INFO - 0:00:32.856 - .c : ------------ [main (2) ] INFO - 0:00:32.857 - addListener (context: com.bubblesoft.android.bubbleupnp.MainTabActivity@ecb4f02) [qtp89477530-34251 (34251) ] SEVERE - 0:00:32.857 - watchdog: Jetty request++: 1 [qtp89477530-34251 (34251) ] INFO - 0:00:32.858 - wake lock acquired [qtp89477530-34251 (34251) ] INFO - 0:00:32.863 - .a : WiFi lock acquired [main (2) ] INFO - 0:00:32.881 - .d0 : started task TimeTask: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:32.881 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [main (2) ] WARNING - 0:00:32.925 - getBubbleUPnPServerFFmpegDecodeREST: not supported for OpenHome renderers [main (2) ] INFO - 0:00:32.927 - .b : selected item: Both Sides Now [main (2) ] INFO - 0:00:32.928 - selected item changed: Both Sides Now [main (2) ] INFO - 0:00:33.17 - selected item changed: Both Sides Now [cling-5 (34223) ] WARNING - 0:00:33.868 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 1, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [qtp89477530-34251 (34251) ] INFO - 0:00:34.426 - tidal: status: ACTIVE, highestSoundQuality: LOW [qtp89477530-34251 (34251) ] INFO - 0:00:34.428 - tidal login: userId: 26380246, sessionId: 941e65ad-7ebf-4b3f-9ad7-cfbf1642e099, countryCode: GB [qtp89477530-34251 (34251) ] WARNING - 0:00:34.591 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34251 (34251) ] SEVERE - 0:00:34.597 - watchdog: Jetty request--: 0 [qtp89477530-34251 (34251) ] INFO - 0:00:34.599 - wake lock released [qtp89477530-34251 (34251) ] INFO - 0:00:34.604 - .a : WiFi lock released [qtp89477530-34251 (34251) ] WARNING - 0:00:34.613 - /proxy/tidal/A20C0C4A662775EA146E4F463D86DAB1.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [cling-5 (34223) ] WARNING - 0:00:34.925 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 3, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [cling-7 (34225) ] WARNING - 0:00:34.967 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 2, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [cling-6 (34224) ] WARNING - 0:00:35.15 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 1, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [cling-6 (34224) ] WARNING - 0:00:35.39 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 2, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [cling-6 (34224) ] WARNING - 0:00:36.638 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 3, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:00:36.651 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [cling-5 (34223) ] WARNING - 0:00:37.177 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 3, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:00:37.533 - .b : selected item: You're My Thrill [main (2) ] INFO - 0:00:37.536 - selected item changed: You're My Thrill [main (2) ] INFO - 0:00:37.641 - selected item changed: You're My Thrill [main (2) ] INFO - 0:00:37.866 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [main (2) ] WARNING - 0:00:37.925 - getBubbleUPnPServerFFmpegDecodeREST: not supported for OpenHome renderers [main (2) ] INFO - 0:00:54.267 - stop: main thread [PlaybackAction: idle (34294) ] INFO - 0:00:54.269 - stop: worker thread enter [cling-5 (34223) ] WARNING - 0:00:54.626 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 4, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [PlaybackAction:stop (34294) ] INFO - 0:00:54.827 - .n : stop worker thread took: 557 ms [cling-7 (34225) ] WARNING - 0:00:54.836 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 5, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [cling-7 (34225) ] WARNING - 0:00:54.866 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 4, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:00:54.873 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [cling-5 (34223) ] WARNING - 0:00:54.942 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 4, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:00:54.953 - .d0 : stopping task TimeTask: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:00:54.955 - ignoreTransportStateChangeForTrackAdvance: true, allowTrackAdvance: true [main (2) ] INFO - 0:00:55.36 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.24 - discovery maintenance: Majik DSM 530:Majik DSM: alive: true [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.40 - discovery maintenance: NAS1F8CF0: alive: true [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.50 - discovery maintenance: MinimServer[NAS1F8CF0]: alive: true [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.54 - I/O exception (s3.e0) caught when processing request to {}->http://192.168.0.13:5001: The target server failed to respond [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.56 - Retrying request to {}->http://192.168.0.13:5001 [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.66 - discovery maintenance: Universal Media Server: alive: true [AndroidUpnpService-DiscoveryMaintenance ] INFO - 0:02:01.77 - discovery maintenance: Linn Kazoo Server [Thiscomputer-6]: alive: true [main (2) ] INFO - 0:02:10.689 - playItem: main thread [PlaybackAction: idle (34294) ] INFO - 0:02:10.690 - playItem: worker thread enter [qtp89477530-34304 (34304) ] INFO - 0:02:10.914 - .c : REQUEST HEADERS [PlaybackAction:playItem (34294) ] INFO - 0:02:10.915 - .n : playItem worker thread took: 222 ms [qtp89477530-34304 (34304) ] INFO - 0:02:10.916 - .c : GET /proxy/tidal/2A8881D0E6A8AE91CAE11E11B34BA8E2.flac HTTP/1.1 {} 192.168.0.18 1663661771340 [qtp89477530-34304 (34304) ] INFO - 0:02:10.917 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:10.918 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:10.918 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:10.920 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:10.922 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:10.923 - .c : ------------ [cling-55 (34305) ] WARNING - 0:02:10.924 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 6, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [qtp89477530-34304 (34304) ] SEVERE - 0:02:10.924 - watchdog: Jetty request++: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:10.925 - wake lock acquired [qtp89477530-34304 (34304) ] INFO - 0:02:10.928 - .a : WiFi lock acquired [qtp89477530-34304 (34304) ] WARNING - 0:02:11.68 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.69 - watchdog: Jetty request--: 0 [qtp89477530-34304 (34304) ] INFO - 0:02:11.71 - wake lock released [qtp89477530-34304 (34304) ] INFO - 0:02:11.73 - .a : WiFi lock released [qtp89477530-34304 (34304) ] WARNING - 0:02:11.81 - /proxy/tidal/2A8881D0E6A8AE91CAE11E11B34BA8E2.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [qtp89477530-34304 (34304) ] INFO - 0:02:11.98 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:11.99 - .c : GET /proxy/tidal/88D90C47CD1C57BF332809E7B9B06B6D.flac HTTP/1.1 {} 192.168.0.18 1663661771524 [qtp89477530-34304 (34304) ] INFO - 0:02:11.100 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.101 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:11.101 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:11.102 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:11.102 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:11.102 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.103 - watchdog: Jetty request++: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.104 - wake lock acquired [qtp89477530-34304 (34304) ] INFO - 0:02:11.107 - .a : WiFi lock acquired [qtp89477530-34304 (34304) ] WARNING - 0:02:11.250 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.252 - watchdog: Jetty request--: 0 [qtp89477530-34304 (34304) ] INFO - 0:02:11.252 - wake lock released [qtp89477530-34304 (34304) ] INFO - 0:02:11.263 - .a : WiFi lock released [qtp89477530-34304 (34304) ] WARNING - 0:02:11.276 - /proxy/tidal/88D90C47CD1C57BF332809E7B9B06B6D.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [cling-56 (34306) ] WARNING - 0:02:11.280 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 7, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [qtp89477530-34304 (34304) ] INFO - 0:02:11.296 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:11.297 - .c : GET /proxy/tidal/CE3C6975829B139AC7B359B3C4FB411C.flac HTTP/1.1 {} 192.168.0.18 1663661771721 [qtp89477530-34304 (34304) ] INFO - 0:02:11.298 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.300 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:11.301 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:11.302 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:11.304 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:11.305 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.306 - watchdog: Jetty request++: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.307 - wake lock acquired [qtp89477530-34304 (34304) ] INFO - 0:02:11.310 - .a : WiFi lock acquired [cling-55 (34305) ] WARNING - 0:02:11.348 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 5, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [cling-56 (34306) ] WARNING - 0:02:11.350 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 5, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:02:11.360 - .d0 : started task TimeTask: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:02:11.361 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [main (2) ] WARNING - 0:02:11.418 - getBubbleUPnPServerFFmpegDecodeREST: not supported for OpenHome renderers [main (2) ] INFO - 0:02:11.421 - .b : selected item: Sometimes I'm Happy [main (2) ] INFO - 0:02:11.423 - selected item changed: Sometimes I'm Happy [main (2) ] INFO - 0:02:11.493 - selected item changed: Sometimes I'm Happy [qtp89477530-34297 (34297) ] INFO - 0:02:11.508 - .c : REQUEST HEADERS [qtp89477530-34297 (34297) ] INFO - 0:02:11.509 - .c : GET /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg HTTP/1.1 {} 192.168.0.14 1663661771934 [qtp89477530-34297 (34297) ] INFO - 0:02:11.510 - .c : Connection: keep-alive [qtp89477530-34297 (34297) ] INFO - 0:02:11.511 - .c : Accept-Encoding: gzip [qtp89477530-34297 (34297) ] INFO - 0:02:11.512 - .c : User-Agent: BubbleDS UPnP/1.1 [qtp89477530-34297 (34297) ] INFO - 0:02:11.513 - .c : Host: 192.168.0.14:57745 [qtp89477530-34297 (34297) ] INFO - 0:02:11.514 - .c : ------------ [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.515 - watchdog: Jetty request++: 2 [qtp89477530-34304 (34304) ] WARNING - 0:02:11.586 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.588 - watchdog: Jetty request--: 1 [qtp89477530-34304 (34304) ] WARNING - 0:02:11.589 - /proxy/tidal/CE3C6975829B139AC7B359B3C4FB411C.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [qtp89477530-34304 (34304) ] INFO - 0:02:11.612 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:11.614 - .c : GET /proxy/tidal/BFA522BF42A8A6A251325CA870741494.flac HTTP/1.1 {} 192.168.0.18 1663661772038 [qtp89477530-34304 (34304) ] INFO - 0:02:11.615 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.616 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:11.618 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:11.619 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:11.620 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:11.621 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.622 - watchdog: Jetty request++: 2 [cling-59 (34310) ] WARNING - 0:02:11.641 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 6, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:02:11.645 - .b : selected item: Don't Worry 'Bout Me [main (2) ] INFO - 0:02:11.647 - selected item changed: Don't Worry 'Bout Me [cling-57 (34308) ] WARNING - 0:02:11.648 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 6, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [qtp89477530-34297 (34297) ] INFO - 0:02:11.653 - proxying: /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg => http://resources.tidal.com/images/14e98a8a/b363/4eaa/a3e6/193900561728/160x160.jpg [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.661 - watchdog: proxy request++: 3 [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.692 - watchdog: Jetty request--: 2 [ExternalProxyServlet-34314 (34314) ] INFO - 0:02:11.714 - proxy: onResponseComplete: total response bytes: 7493 [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.714 - watchdog: proxy request (complete)--: 1 [main (2) ] INFO - 0:02:11.722 - selected item changed: Don't Worry 'Bout Me [qtp89477530-34297 (34297) ] INFO - 0:02:11.729 - .c : REQUEST HEADERS [qtp89477530-34297 (34297) ] INFO - 0:02:11.730 - .c : GET /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg HTTP/1.1 {} 192.168.0.14 1663661772155 [qtp89477530-34297 (34297) ] INFO - 0:02:11.731 - .c : Connection: keep-alive [qtp89477530-34297 (34297) ] INFO - 0:02:11.732 - .c : Accept-Encoding: gzip [qtp89477530-34297 (34297) ] INFO - 0:02:11.734 - .c : User-Agent: BubbleDS UPnP/1.1 [qtp89477530-34297 (34297) ] INFO - 0:02:11.735 - .c : Host: 192.168.0.14:57745 [qtp89477530-34297 (34297) ] INFO - 0:02:11.739 - .c : ------------ [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.740 - watchdog: Jetty request++: 2 [qtp89477530-34297 (34297) ] INFO - 0:02:11.742 - proxying: /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg => http://resources.tidal.com/images/14e98a8a/b363/4eaa/a3e6/193900561728/160x160.jpg [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.745 - watchdog: proxy request++: 3 [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.747 - watchdog: Jetty request--: 2 [qtp89477530-34304 (34304) ] WARNING - 0:02:11.756 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.758 - watchdog: Jetty request--: 1 [qtp89477530-34304 (34304) ] WARNING - 0:02:11.760 - /proxy/tidal/BFA522BF42A8A6A251325CA870741494.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [ExternalProxyServlet-34314 (34314) ] INFO - 0:02:11.825 - proxy: onResponseComplete: total response bytes: 7493 [qtp89477530-34297 (34297) ] SEVERE - 0:02:11.825 - watchdog: proxy request (complete)--: 0 [qtp89477530-34297 (34297) ] INFO - 0:02:11.826 - wake lock released [qtp89477530-34297 (34297) ] INFO - 0:02:11.830 - .a : WiFi lock released [qtp89477530-34304 (34304) ] INFO - 0:02:11.832 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:11.833 - .c : GET /proxy/tidal/89A48CFCF69C66DF10D2D6411F32284A.flac HTTP/1.1 {} 192.168.0.18 1663661772258 [qtp89477530-34304 (34304) ] INFO - 0:02:11.835 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.837 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:11.838 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:11.840 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:11.841 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:11.842 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:11.844 - watchdog: Jetty request++: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:11.847 - wake lock acquired [qtp89477530-34304 (34304) ] INFO - 0:02:11.851 - .a : WiFi lock acquired [cling-59 (34310) ] WARNING - 0:02:11.866 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 7, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [cling-57 (34308) ] WARNING - 0:02:11.875 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 7, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:02:11.877 - .b : selected item: Stormy Weather [main (2) ] INFO - 0:02:11.879 - selected item changed: Stormy Weather [main (2) ] INFO - 0:02:11.968 - selected item changed: Stormy Weather [qtp89477530-34316 (34316) ] INFO - 0:02:11.976 - .c : REQUEST HEADERS [qtp89477530-34316 (34316) ] INFO - 0:02:11.978 - .c : GET /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg HTTP/1.1 {} 192.168.0.14 1663661772402 [qtp89477530-34316 (34316) ] INFO - 0:02:11.979 - .c : Connection: keep-alive [qtp89477530-34316 (34316) ] INFO - 0:02:11.980 - .c : Accept-Encoding: gzip [qtp89477530-34316 (34316) ] INFO - 0:02:11.981 - .c : User-Agent: BubbleDS UPnP/1.1 [qtp89477530-34316 (34316) ] INFO - 0:02:11.982 - .c : Host: 192.168.0.14:57745 [qtp89477530-34316 (34316) ] INFO - 0:02:11.983 - .c : ------------ [qtp89477530-34316 (34316) ] SEVERE - 0:02:11.984 - watchdog: Jetty request++: 2 [qtp89477530-34316 (34316) ] INFO - 0:02:11.987 - proxying: /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg => http://resources.tidal.com/images/14e98a8a/b363/4eaa/a3e6/193900561728/160x160.jpg [qtp89477530-34316 (34316) ] SEVERE - 0:02:11.989 - watchdog: proxy request++: 3 [qtp89477530-34316 (34316) ] SEVERE - 0:02:11.990 - watchdog: Jetty request--: 2 [qtp89477530-34297 (34297) ] SEVERE - 0:02:12.21 - watchdog: proxy request (complete)--: 1 [ExternalProxyServlet-34314 (34314) ] INFO - 0:02:12.21 - proxy: onResponseComplete: total response bytes: 7493 [qtp89477530-34304 (34304) ] WARNING - 0:02:12.34 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.38 - watchdog: Jetty request--: 0 [qtp89477530-34304 (34304) ] INFO - 0:02:12.39 - wake lock released [qtp89477530-34304 (34304) ] INFO - 0:02:12.42 - .a : WiFi lock released [qtp89477530-34304 (34304) ] WARNING - 0:02:12.48 - /proxy/tidal/89A48CFCF69C66DF10D2D6411F32284A.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [qtp89477530-34304 (34304) ] INFO - 0:02:12.65 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:12.66 - .c : GET /proxy/tidal/034DAEAB7B08AD063E65E2C97AAEA0CC.flac HTTP/1.1 {} 192.168.0.18 1663661772491 [qtp89477530-34304 (34304) ] INFO - 0:02:12.67 - .c : Icy-MetaData: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:12.68 - .c : Connection: close [qtp89477530-34304 (34304) ] INFO - 0:02:12.69 - .c : Range: bytes=0- [qtp89477530-34304 (34304) ] INFO - 0:02:12.70 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34304 (34304) ] INFO - 0:02:12.72 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:12.73 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.73 - watchdog: Jetty request++: 1 [qtp89477530-34304 (34304) ] INFO - 0:02:12.74 - wake lock acquired [qtp89477530-34304 (34304) ] INFO - 0:02:12.77 - .a : WiFi lock acquired [cling-59 (34310) ] WARNING - 0:02:12.102 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 8, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [qtp89477530-34304 (34304) ] WARNING - 0:02:12.316 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.318 - watchdog: Jetty request--: 0 [qtp89477530-34304 (34304) ] INFO - 0:02:12.320 - wake lock released [cling-57 (34308) ] WARNING - 0:02:12.320 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 8, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [qtp89477530-34304 (34304) ] INFO - 0:02:12.323 - .a : WiFi lock released [main (2) ] INFO - 0:02:12.325 - .b : selected item: I Wish I Were in Love Again [main (2) ] INFO - 0:02:12.328 - selected item changed: I Wish I Were in Love Again [qtp89477530-34304 (34304) ] WARNING - 0:02:12.330 - /proxy/tidal/034DAEAB7B08AD063E65E2C97AAEA0CC.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [qtp89477530-34297 (34297) ] INFO - 0:02:12.345 - .c : REQUEST HEADERS [qtp89477530-34297 (34297) ] INFO - 0:02:12.346 - .c : GET /proxy/tidal/A20C0C4A662775EA146E4F463D86DAB1.flac HTTP/1.1 {} 192.168.0.18 1663661772771 [qtp89477530-34297 (34297) ] INFO - 0:02:12.348 - .c : Icy-MetaData: 1 [qtp89477530-34297 (34297) ] INFO - 0:02:12.349 - .c : Connection: close [qtp89477530-34297 (34297) ] INFO - 0:02:12.350 - .c : Range: bytes=0- [qtp89477530-34297 (34297) ] INFO - 0:02:12.351 - .c : User-Agent: LWIP/1.4.2-linn [qtp89477530-34297 (34297) ] INFO - 0:02:12.352 - .c : Host: 192.168.0.14:57745 [qtp89477530-34297 (34297) ] INFO - 0:02:12.353 - .c : ------------ [qtp89477530-34297 (34297) ] SEVERE - 0:02:12.354 - watchdog: Jetty request++: 1 [qtp89477530-34297 (34297) ] INFO - 0:02:12.355 - wake lock acquired [qtp89477530-34297 (34297) ] INFO - 0:02:12.358 - .a : WiFi lock acquired [cling-59 (34310) ] WARNING - 0:02:12.388 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 9, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [cling-57 (34308) ] WARNING - 0:02:12.398 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 9, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:02:12.420 - selected item changed: I Wish I Were in Love Again [qtp89477530-34304 (34304) ] INFO - 0:02:12.429 - .c : REQUEST HEADERS [qtp89477530-34304 (34304) ] INFO - 0:02:12.430 - .c : GET /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg HTTP/1.1 {} 192.168.0.14 1663661772855 [qtp89477530-34304 (34304) ] INFO - 0:02:12.432 - .c : Connection: keep-alive [qtp89477530-34304 (34304) ] INFO - 0:02:12.433 - .c : Accept-Encoding: gzip [qtp89477530-34304 (34304) ] INFO - 0:02:12.434 - .c : User-Agent: BubbleDS UPnP/1.1 [qtp89477530-34304 (34304) ] INFO - 0:02:12.435 - .c : Host: 192.168.0.14:57745 [qtp89477530-34304 (34304) ] INFO - 0:02:12.436 - .c : ------------ [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.436 - watchdog: Jetty request++: 2 [qtp89477530-34304 (34304) ] INFO - 0:02:12.439 - proxying: /proxy/H4sIAAAAAAAAAA3DAQqAIBAEwB-5Xpppv7nqMCNR1KDn18CcY9QVaNLL03bpaqSDb7WXjJQ5SgdZCZ49YzPOwAoz2IgDBRO0nh0tkwc5_f7VVeMHsSoJflIAAAA=.jpg => http://resources.tidal.com/images/14e98a8a/b363/4eaa/a3e6/193900561728/160x160.jpg [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.441 - watchdog: proxy request++: 3 [qtp89477530-34304 (34304) ] SEVERE - 0:02:12.443 - watchdog: Jetty request--: 2 [main (2) ] INFO - 0:02:12.458 - .b : selected item: Both Sides Now [main (2) ] INFO - 0:02:12.459 - selected item changed: Both Sides Now [qtp89477530-34316 (34316) ] SEVERE - 0:02:12.474 - watchdog: proxy request (complete)--: 1 [ExternalProxyServlet-34314 (34314) ] INFO - 0:02:12.474 - proxy: onResponseComplete: total response bytes: 7493 [qtp89477530-34297 (34297) ] WARNING - 0:02:12.490 - Tidal: {"status":401,"subStatus":5003,"userMessage":"Requested quality is not allowed in user's subscription"} [qtp89477530-34297 (34297) ] SEVERE - 0:02:12.492 - watchdog: Jetty request--: 0 [qtp89477530-34297 (34297) ] INFO - 0:02:12.493 - wake lock released [qtp89477530-34297 (34297) ] INFO - 0:02:12.497 - .a : WiFi lock released [qtp89477530-34297 (34297) ] WARNING - 0:02:12.503 - /proxy/tidal/A20C0C4A662775EA146E4F463D86DAB1.flac retrofit.RetrofitError: 401 at retrofit.RestAdapter$RestHandler.invokeRequest(SourceFile:55) at retrofit.RestAdapter$RestHandler.invoke(SourceFile:5) at java.lang.reflect.Proxy.invoke(Proxy.java:1006) at $Proxy12.getTrackPlaybackInfoPostPaywall(Unknown Source) at com.bubblesoft.tidal.TidalClient.getTrackStreamUrl(SourceFile:2) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.getCachedStreamUrl(SourceFile:6) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.TidalServlet.doGet(SourceFile:23) at javax.servlet.http.b.service(SourceFile:4) at javax.servlet.http.b.service(SourceFile:29) at org.eclipse.jetty.servlet.g.P0(SourceFile:15) at org.eclipse.jetty.servlet.f$a.a(SourceFile:17) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestWatchdogFilter.doFilter(SourceFile:2) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at com.bubblesoft.android.bubbleupnp.mediaserver.servlet.RequestLogFilter.doFilter(SourceFile:3) at org.eclipse.jetty.servlet.f$a.a(SourceFile:7) at org.eclipse.jetty.servlet.f.O0(SourceFile:16) at hn.c.O0(SourceFile:15) at org.eclipse.jetty.servlet.f.P0(SourceFile:25) at hn.c.P0(SourceFile:34) at hn.h.o0(SourceFile:2) at hn.f.o0(SourceFile:3) at hn.g.o0(SourceFile:2) at gn.s.W0(SourceFile:8) at gn.b.C(SourceFile:23) at gn.b.D(SourceFile:38) at gn.b$d.c(SourceFile:1) at an.n.q(SourceFile:135) at an.n.c(SourceFile:1) at gn.d.b(SourceFile:6) at dn.g.E(SourceFile:1) at dn.g$a.run(SourceFile:1) at qn.a.N0(SourceFile:1) at qn.a$c.run(SourceFile:4) at java.lang.Thread.run(Thread.java:923) [main (2) ] INFO - 0:02:12.524 - selected item changed: Both Sides Now [cling-58 (34309) ] WARNING - 0:02:12.567 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 10, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [cling-57 (34308) ] WARNING - 0:02:12.570 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 10, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:02:12.599 - .b : selected item: You're My Thrill [main (2) ] INFO - 0:02:12.600 - selected item changed: You're My Thrill [main (2) ] INFO - 0:02:12.652 - selected item changed: You're My Thrill [cling-57 (34308) ] WARNING - 0:02:14.145 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 11, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:02:14.594 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [cling-57 (34308) ] WARNING - 0:02:14.640 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 11, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:02:14.704 - ignoreTransportStateChangeForTrackAdvance: false, allowTrackAdvance: true [main (2) ] WARNING - 0:02:14.787 - getBubbleUPnPServerFFmpegDecodeREST: not supported for OpenHome renderers [main (2) ] INFO - 0:02:16.291 - stop: main thread [PlaybackAction: idle (34294) ] INFO - 0:02:16.292 - stop: worker thread enter [cling-57 (34308) ] WARNING - 0:02:16.715 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 8, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [PlaybackAction:stop (34294) ] INFO - 0:02:16.836 - .n : stop worker thread took: 543 ms [cling-58 (34309) ] WARNING - 0:02:16.853 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Sender/event/cb SEQUENCE: 9, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-342 [cling-59 (34310) ] WARNING - 0:02:16.888 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Info/event/cb SEQUENCE: 12, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-339 [main (2) ] INFO - 0:02:16.894 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [cling-57 (34308) ] WARNING - 0:02:16.916 - .b : Invalid subscription ID, no active subscription: (a) NOTIFY /dev/4c494e4e-0026-0f22-21c3-01444235013f/svc/av-openhome-org/Playlist/event/cb SEQUENCE: 12, subId: uuid:4c494e4e-0026-0f22-21c3-01444235013f-336 [main (2) ] INFO - 0:02:16.919 - .d0 : stopping task TimeTask: Majik DSM 530:Majik DSM [main (2) ] INFO - 0:02:16.921 - ignoreTransportStateChangeForTrackAdvance: true, allowTrackAdvance: true [main (2) ] INFO - 0:02:16.991 - setSeekBarDetailsText: FLAC • 96 kHz • 24 bits [main (2) ] INFO - 0:02:27.176 - onPause()@ecb4f02:972 [main (2) ] INFO - 0:02:27.178 - removed listener: 4 listeners [main (2) ] INFO - 0:02:27.180 - removed listener: 3 listeners [main (2) ] INFO - 0:02:27.181 - removed listener: 2 listeners [main (2) ] INFO - 0:02:27.182 - removeListener (context: com.bubblesoft.android.bubbleupnp.MainTabActivity@ecb4f02) [main (2) ] INFO - 0:02:27.183 - removed listener: 1 listeners [main (2) ] INFO - 0:02:27.184 - removed listener: 0 listeners [main (2) ] INFO - 0:02:28.44 - onStop()@ecb4f02:972 [main (2) ] INFO - 0:02:30.12 - onDestroy()@ecb4f02:972 [main (2) ] INFO - 0:02:30.57 - saveMediaServerState: Local and Cloud [main (2) ] INFO - 0:02:30.58 - positionStack: [] [main (2) ] INFO - 0:02:30.58 - itemList: Bundle[{firstVisiblePosition=0}] [main (2) ] INFO - 0:02:30.58 - containerPath: [] [main (2) ] INFO - 0:02:30.64 - RadioActivity: onDestroy [main (2) ] INFO - 0:02:30.76 - onTaskRemoved: Intent { act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.bubblesoft.android.bubbleds2/com.bubblesoft.android.bubbleupnp.MainActivity }, mode: 2 [main (2) ] INFO - 0:02:30.100 - onDestroy: start [main (2) ] INFO - 0:02:30.115 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Product [main (2) ] INFO - 0:02:30.116 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Playlist [main (2) ] INFO - 0:02:30.117 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Radio [main (2) ] INFO - 0:02:30.119 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Info [main (2) ] INFO - 0:02:30.120 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Receiver [main (2) ] INFO - 0:02:30.122 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Credentials [main (2) ] INFO - 0:02:30.124 - stopped subscription callback for service (n) ServiceId: urn:av-openhome-org:serviceId:Volume [main (2) ] INFO - 0:02:30.125 - Qobuz: username: set [main (2) ] INFO - 0:02:30.126 - fireRootContentChanged [main (2) ] INFO - 0:02:30.127 - active renderer: none [main (2) ] INFO - 0:02:30.129 - VolumeProvider: ignoring: null renderer [main (2) ] INFO - 0:02:30.133 - stopping Jetty... [main (2) ] INFO - 0:02:30.180 - stopped {} [main (2) ] INFO - 0:02:30.181 - stopped {} [main (2) ] INFO - 0:02:30.183 - stopped {} [main (2) ] INFO - 0:02:30.187 - stopped {} [main (2) ] INFO - 0:02:30.242 - stopped {} [main (2) ] INFO - 0:02:30.296 - stopped {} [main (2) ] INFO - 0:02:30.299 - stopped {} [main (2) ] INFO - 0:02:30.352 - stopped {} [main (2) ] INFO - 0:02:30.405 - stopped Jetty [main (2) ] INFO - 0:02:30.407 - removed local media server [main (2) ] INFO - 0:02:30.414 - discovery maintenance: waiting for executor termination... [main (2) ] INFO - 0:02:30.415 - discovery maintenance: executor terminated [main (2) ] INFO - 0:02:30.416 - discovery maintenance: stopped executor [main (2) ] INFO - 0:02:30.418 - waiting for UPnP service to shutdown... [AndroidUpnpService-Shutdown (34326) ] INFO - 0:02:30.419 - .d : >>> Shutting down UPnP service... [main (2) ] WARNING - 0:02:33.918 - waiting for UPnP service to shutdown timeouted [main (2) ] INFO - 0:02:33.919 - removed LAN BubbleUPnP Server [main (2) ] WARNING - 0:02:33.920 - wake lock already released [main (2) ] INFO - 0:02:33.928 - smb: shutdown SMB Manager [main (2) ] INFO - 0:02:33.933 - .c : Going to close all remaining connections [main (2) ] INFO - 0:02:33.934 - App shutdown [main (2) ] INFO - 0:02:33.937 - .n : AndroidUpnpService.onDestroy() took: 3837 ms [main (2) ] INFO - 0:02:33.939 - System.exit()