02/20 17:02:47 Info: Starting RoonServer v2.0 (build 1368) production on linuxx64
02/20 17:02:47 Info: Local time is 2/20/2024 5:02:47 PM, UTC time is 2/20/2024 10:02:47 PM
02/20 17:02:47 Debug: Init DefaultBackingApi: HttpClient
02/20 17:02:47 Trace: Checking if we are already running
02/20 17:02:47 Info: get lock file path: /tmp/.rnsems0-
02/20 17:02:47 Info: GetLockFile, fd: 60
02/20 17:02:47 Info: GetLockFile, res: 0
02/20 17:02:47 Trace: Nope, we are the only one running
02/20 17:02:47 Trace: [bits] myinfo: {"os":"Linux 5.6.13-100.sgc.fc30.x86_64","platform":"linuxx64","machineversion":200001368,"branch":"production","appmodifier":"","appname":"RoonServer"}
02/20 17:02:47 Trace: [roondns] flushed 1 last-known-good entries
02/20 17:02:47 Debug: [easyhttp] [1] POST to https://bits.roonlabs.net/1/q/roon.base.,roon.internet_discovery. returned after 271 ms, status code: 200, request body size: 152 B
02/20 17:02:47 Trace: [bits] updated bits, in 328ms
02/20 17:02:47 Info: Is 64 bit? True
02/20 17:02:47 Info: Command Line Argument: -watchdogport=35621
02/20 17:02:47 Trace: [realtime] fetching time from NTP server
02/20 17:02:47 Trace: [orbit] init seq=0, pending=0, spcount=0, uidcount=0
02/20 17:02:47 Trace: [realtime] Got time from NTP: 2/20/2024 10:02:47 PM UTC (3917455367921ms)
02/20 17:02:47 Trace: [realtime] Updated clock skew to -00:00:00.0034973 (-3.4973ms)
02/20 17:02:47 Trace: [ipaddresses] enumerating addresses
02/20 17:02:47 Trace: [ipaddresses]    FOUND   lo 127.0.0.1
02/20 17:02:47 Trace: [ipaddresses]    FOUND   eth0 192.168.1.78
02/20 17:02:47 Info: [broker] starting 4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:47 Trace: [httpcache] loaded 0 cache entries from /var/roon/RoonServer/Cache/httpcache_2.db, current: 0mb / 128mb
02/20 17:02:48 Info: [broker/locations] adding storage location: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb
02/20 17:02:48 Info: [broker/locations] media availability: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb is online
02/20 17:02:48 Info: [broker/locations] adding storage location: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8
02/20 17:02:48 Info: [broker/locations] media availability: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8 is online
02/20 17:02:48 Info: [broker/locations] adding storage location: Offline:Name=Offline:Id=f1e4b43f-f643-47ba-b875-fd93b32a6006
02/20 17:02:48 Debug: [broker/locations] load pre 1.3 automounts, strlist: 
02/20 17:02:48 Info: [broker/locations] adding storage location: DefaultMusicFolder:Name=Music Folder:Id=29bdcae6-b5fd-d666-27bf-a1f6afceabac
02/20 17:02:48 Debug: [broker/locations] write pre 1.3 automounts, strlist: 
02/20 17:02:48 Info: [media] resolved oop decoder to /usr/bin/ffmpeg
02/20 17:02:48 Info: [remoting/distributedbroker] V2 Protocol Support is enabled
02/20 17:02:48 Info: [remoting/brokerserver] Enabling broker server (V2)
02/20 17:02:48 Info: [broker/httpserver] HTTP server listening on port 9330
02/20 17:02:48 Info: [broker/httpserver] HTTPS server listening on port 55000
02/20 17:02:48 Trace: [broker/backups] initializing
02/20 17:02:48 Info: [loadstatus] account loading is complete
02/20 17:02:48 Trace: [broker/accounts] Data updated. AccountStatus=NoAccountConfigured MachineStatus=NeedsAccount UserId=<none>
02/20 17:02:48 Trace: [broker/accounts] [heartbeat] now=2/20/2024 10:02:48 PM nextauthrefresh=2/20/2024 9:57:48 PM nextmachineallocate=1/1/0001 12:00:00 AM
02/20 17:02:48 Trace:  [broker/accounts]   doing auth refresh, next=2/20/2024 9:57:48 PM
02/20 17:02:48 Trace: [broker/accounts]    doing machine allocate, next=2/21/2024 2:02:48 AM
02/20 17:02:48 Trace: [inetdiscovery] added device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c in addr:__ADDR__
02/20 17:02:48 Info: [libraryapi] set first run time 2/20/2024 10:02:48 PM
02/20 17:02:48 Trace: [devicedb] initializing
02/20 17:02:48 Info: [raatserver] local RAATServer is disabled due to ROON_DISABLE_LOCAL_AUDIO_DEVICES environment variable
02/20 17:02:48 Warn: [multicastreceiver] couldn't bind to iface 127.0.0.1:5350, message: Invalid argument
02/20 17:02:48 Warn: [multicastreceiver] couldn't bind to iface 192.168.1.78:5350, message: Invalid argument
02/20 17:02:48 Trace: [broker/services] refreshing metadata URL bits
02/20 17:02:48 Info: [broker/info] Adding tidal=max parameter to Roon services requests
02/20 17:02:48 Info: Using FTMSI-B Cache
02/20 17:02:48 Info: connected to watchdogport: 35621
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys because it is not a /dev/sd[0-9]* (mountline: sysfs /sys sysfs rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:48 Info: [broker/messaging] initializing
02/20 17:02:48 Info: [broker/dropbox] no account configured
02/20 17:02:48 Info: [metadata] initializing
02/20 17:02:48 Info: [remoting] loaded protocol hash 1c975d142ef5a6df161394ca3170f80e22b8100d from /opt/RoonServer/Appliance/Roon.Broker.Api.Remote.dll
02/20 17:02:48 Debug: [oauthcb] update bit: use_tidal_max=True protocolversion=3
02/20 17:02:48 Info: [remoting/brokerserver] Remoting server (V1) listening on port 9331
02/20 17:02:48 Info: [remoting/brokerserver] Remoting server (V2) listening on port 9332
02/20 17:02:48 Debug: [oauthcb] update bit: use_tidal_max=True protocolversion=3
02/20 17:02:48 Trace: [inetdiscovery] added service com.roonlabs.roon.broker.tcp for device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:48 Trace: [inetdiscovery] added service com.roonlabs.roon.broker.tcpv2 for device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:48 Trace: [inetdiscovery] added service com.roonlabs.roon.broker.http for device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:48 Info: [music] first pass through media thread is done
02/20 17:02:48 Trace: [geoip] GET https://geoip.roonlabs.net/geoip/1/lookup
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /proc
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /proc because it is not a /dev/sd[0-9]* (mountline: proc /proc proc rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:48 Info: [transport] initializing
02/20 17:02:48 Info: [loadstatus] IsLocalLibraryLoading True => False
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /dev because it is not a /dev/sd[0-9]* (mountline: devtmpfs /dev devtmpfs rw,nosuid,size=4017468k,nr_inodes=1004367,mode=755 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/security
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/security because it is not a /dev/sd[0-9]* (mountline: securityfs /sys/kernel/security securityfs rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/shm
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /dev/shm because it is not a /dev/sd[0-9]* (mountline: tmpfs /dev/shm tmpfs rw,nosuid,nodev 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/pts
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /dev/pts because it is not a /dev/sd[0-9]* (mountline: devpts /dev/pts devpts rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /run
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /run because it is not a /dev/sd[0-9]* (mountline: tmpfs /run tmpfs rw,nosuid,nodev,mode=755 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup because it is not a /dev/sd[0-9]* (mountline: tmpfs /sys/fs/cgroup tmpfs ro,nosuid,nodev,noexec,mode=755 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/unified
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/unified because it is not a /dev/sd[0-9]* (mountline: cgroup2 /sys/fs/cgroup/unified cgroup2 rw,nosuid,nodev,noexec,relatime,nsdelegate 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/systemd
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/systemd because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/systemd cgroup rw,nosuid,nodev,noexec,relatime,xattr,name=systemd 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/pstore
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/pstore because it is not a /dev/sd[0-9]* (mountline: pstore /sys/fs/pstore pstore rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/bpf
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/bpf because it is not a /dev/sd[0-9]* (mountline: none /sys/fs/bpf bpf rw,nosuid,nodev,noexec,relatime,mode=700 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/devices
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/devices because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/devices cgroup rw,nosuid,nodev,noexec,relatime,devices 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/pids
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/pids because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/pids cgroup rw,nosuid,nodev,noexec,relatime,pids 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/freezer
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/freezer because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/freezer cgroup rw,nosuid,nodev,noexec,relatime,freezer 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/cpu,cpuacct
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/cpu,cpuacct because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/cpu,cpuacct cgroup rw,nosuid,nodev,noexec,relatime,cpu,cpuacct 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/net_cls,net_prio
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/net_cls,net_prio because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/net_cls,net_prio cgroup rw,nosuid,nodev,noexec,relatime,net_cls,net_prio 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/hugetlb
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/hugetlb because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/hugetlb cgroup rw,nosuid,nodev,noexec,relatime,hugetlb 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/cpuset
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/cpuset because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/cpuset cgroup rw,nosuid,nodev,noexec,relatime,cpuset 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/perf_event
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/perf_event because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/perf_event cgroup rw,nosuid,nodev,noexec,relatime,perf_event 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/blkio
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/blkio because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/blkio cgroup rw,nosuid,nodev,noexec,relatime,blkio 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/memory
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/memory because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/memory cgroup rw,nosuid,nodev,noexec,relatime,memory 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/config
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/config because it is not a /dev/sd[0-9]* (mountline: configfs /sys/kernel/config configfs rw,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /
02/20 17:02:48 Trace: [devicedb] refreshing, no etag
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/debug
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/debug because it is not a /dev/sd[0-9]* (mountline: debugfs /sys/kernel/debug debugfs rw,relatime 0 0)
02/20 17:02:48 Trace: [devicedb] builtin device db found at /opt/RoonServer/Appliance/devicedb-prod.zip
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/hugepages
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /dev/hugepages because it is not a /dev/sd[0-9]* (mountline: hugetlbfs /dev/hugepages hugetlbfs rw,relatime,pagesize=2M 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/mqueue
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /dev/mqueue because it is not a /dev/sd[0-9]* (mountline: mqueue /dev/mqueue mqueue rw,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /proc/sys/fs/binfmt_misc
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /proc/sys/fs/binfmt_misc because it is not a /dev/sd[0-9]* (mountline: systemd-1 /proc/sys/fs/binfmt_misc autofs rw,relatime,fd=40,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=17478 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/fuse/connections
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/fuse/connections because it is not a /dev/sd[0-9]* (mountline: fusectl /sys/fs/fuse/connections fusectl rw,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /tmp
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /tmp because it is not a /dev/sd[0-9]* (mountline: tmpfs /tmp tmpfs rw,nosuid,nodev 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /boot
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /var/lib/nfs/rpc_pipefs
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /var/lib/nfs/rpc_pipefs because it is not a /dev/sd[0-9]* (mountline: sunrpc /var/lib/nfs/rpc_pipefs rpc_pipefs rw,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /storage
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /storage because it is not a /dev/sd[0-9]* (mountline: /dev/mapper/VolGroup-lv_storage /storage ext4 rw,relatime 0 0)
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /run/user/0
02/20 17:02:48 Debug: [broker/filebrowser/volumeattached] skipping /run/user/0 because it is not a /dev/sd[0-9]* (mountline: tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=806220k,mode=700 0 0)
02/20 17:02:48 Trace: [devicedb] loading device db at /opt/RoonServer/Appliance/devicedb-prod.zip
02/20 17:02:48 Trace: [roondns] flushed 2 last-known-good entries
02/20 17:02:48 Debug: [easyhttp] [3] GET to https://api.roonlabs.net/devicedb/1/devicedb-prod.zip returned after 127 ms, status code: 200, request body size: 0 B
02/20 17:02:48 Debug: [easyhttp] [2] GET to https://api.roonlabs.net/geoip/geoip/1/lookup returned after 203 ms, status code: 200, request body size: 0 B
02/20 17:02:48 Trace: [geoip] GOT {"country_iso":"US","state":"Pennsylvania","city":"West Kensington","latitude":39.9865,"longitude":-75.13907,"postalcode":"","ip":"96.227.131.23","status":"Success","country":"United States of America","source":"ipify","state_iso":"PA","decimal_ip":"1625522967","ipaddress":"96.227.131.23"}
02/20 17:02:48 Trace: [devicedb] loaded 164 vendors, 1047 products from devicedb with timestamp 2/12/2024 5:46:00 PM
02/20 17:02:48 Info: [loadstatus] IsTransportLoading True => False
02/20 17:02:48 Info: [loadstatus] IsStartup True => False
02/20 17:02:48 Info: [mobile] initializing
02/20 17:02:48 Info: [roonapi] initializing
02/20 17:02:48 Trace: [mobile] [remoteconnectivity] Port Verification started due to: initial check at init, not testing port opening because the broker is not loaded and ready.  account status: NoAccountConfigured, machine status: NeedsAccount, load status: NotReady
02/20 17:02:48 Trace: [inetdiscovery] added service com.roonlabs.roon.api.tcp for device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:48 Trace: [inetdiscovery] added service com.roonlabs.roon.api.http for device broker/4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c
02/20 17:02:48 Info: [roonapi] listening on port 9150
02/20 17:02:48 Trace:  [broker/accounts]   doing auth refresh in EnsureAuthReady, next=2/20/2024 11:02:48 PM
02/20 17:02:48 Trace: [mobile] [remoteconnectivity] Port Verification started due to: load status changed, not testing port opening because the broker is not loaded and ready.  account status: NoAccountConfigured, machine status: NeedsAccount, load status: Ready
02/20 17:02:48 Warn: [auth] EnsureAuthReady failed: Result[Status=NotLoggedIn]
02/20 17:02:48 Debug: [easyhttp] [4] POST to https://api.roonlabs.net/roonmobile/1/cores/announce returned after 16 ms, status code: 401, request body size: 636 B
02/20 17:02:48 Info: [stats] 4629mb Virtual, 103mb Physical, 13mb Managed, 223 Handles, 36 Threads
02/20 17:02:49 Trace: [devicedb] loading device db at /var/roon/RoonServer/Cache/devicedb-prod.zip.tmp
02/20 17:02:49 Trace: [devicedb] loaded 165 vendors, 1049 products from devicedb with timestamp 2/20/2024 4:10:26 PM
02/20 17:02:49 Trace: [devicedb] loading device db at /var/roon/RoonServer/Cache/devicedb-prod.zip
02/20 17:02:49 Trace: [devicedb] loaded 165 vendors, 1049 products from devicedb with timestamp 2/20/2024 4:10:26 PM
02/20 17:02:49 Trace: [mobile] [remoteconnectivity] found new upnp device: Device={ DeviceType=urn:schemas-upnp-org:device:WFADevice Version=1 FriendlyName=RBR40 (AP) Manufacturer=NETGEAR,Inc. ModelName=NETGEAR Orbi Mini AC2200 Router UDN=uuid:uuid:4d696e69-444c-164e-9d42- Description=http://www.netgear.com/home/orbi ModelNumber=RBR40 UPC=RBR40 ManufacturerUri=http://www.netgear.com/ ModelUrl=http://www.netgear.com/home/orbiDevice={ DeviceType=urn:schemas-upnp-org:device:WANDevice Version=1 FriendlyName=WANDevice Manufacturer=NETGEAR,Inc. ModelName=NETGEAR Orbi Mini AC2200 Router UDN=uuid:uuid:4d696e69-444c-164e-9d43- Description=WANDevice on NETGEAR RBR40 Orbi Router ModelNumber=RBR40 UPC=606449084528 ManufacturerUri=http://www.netgear.com/ ModelUrl=http://www.netgear.com/home/orbiDevice={ DeviceType=urn:schemas-upnp-org:device:WANConnectionDevice Version=1 FriendlyName=WANConnectionDevice Manufacturer=NETGEAR,Inc. ModelName=NETGEAR Orbi Mini AC2200 Router UDN=uuid:uuid:4d696e69-444c-164e-9d44- Description=WANConnectionDevice on NETGEAR RBR40 Orbi Router ModelNumber=RBR40 UPC=606449084528 ManufacturerUri=http://www.netgear.com/ ModelUrl=http://www.netgear.com/home/orbiService={ ServiceType=urn:schemas-upnp-org:service:WANEthernetLinkConfig ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:WANEthLinkC1 ControlUri=http://192.168.1.189:56688/ctl/WanEth SCPDUri=http://192.168.1.189:56688/WanEth.xml } Service={ ServiceType=urn:schemas-upnp-org:service:WANIPConnection ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:WANIPConn1 ControlUri=http://192.168.1.189:56688/ctl/IPConn SCPDUri=http://192.168.1.189:56688/WANIPCn.xml }  } Service={ ServiceType=urn:schemas-upnp-org:service:WANCommonInterfaceConfig ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:WANCommonIFC1 ControlUri=http://192.168.1.189:56688/ctl/CmnIfCfg SCPDUri=http://192.168.1.189:56688/WANCfg.xml }  } Service={ ServiceType=urn:schemas-upnp-org:service:Layer3Forwarding ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:L3Forwarding1 ControlUri=http://192.168.1.189:56688/ctl/L3F SCPDUri=http://192.168.1.189:56688/L3F.xml }  }
02/20 17:02:49 Trace: [mobile] [remoteconnectivity] Port Verification started due to: found UPnP WANConnectiondevice, not testing port opening because the broker is not loaded and ready.  account status: NoAccountConfigured, machine status: NeedsAccount, load status: Ready
02/20 17:02:49 Trace: [mobile] [remoteconnectivity] found new upnp device: Device={ DeviceType=urn:schemas-upnp-org:device:InternetGatewayDevice Version=2 FriendlyName=Fios Router Manufacturer=Verizon ModelName=CR1000A UDN=uuid:49ef1593-c0b2-44e1-aeff-00ee6c5c950b Description=Fios Router ModelNumber=CR1000A ManufacturerUri=http://www.verizon.com/ ModelUrl=http://www.verizon.com/Device={ DeviceType=urn:schemas-upnp-org:device:WANDevice Version=2 FriendlyName=Fios Router Manufacturer=Verizon ModelName=CR1000A UDN=uuid:49ef1593-c0b2-44e1-aeff-00ee6c5c950c Description=Fios Router ModelNumber=CR1000A UPC=000000000000 ManufacturerUri=http://www.verizon.com/ ModelUrl=http://www.verizon.com/Device={ DeviceType=urn:schemas-upnp-org:device:WANConnectionDevice Version=2 FriendlyName=Fios Router Manufacturer=Verizon ModelName=CR1000A UDN=uuid:49ef1593-c0b2-44e1-aeff-00ee6c5c950d Description=Fios Router ModelNumber=CR1000A UPC=000000000000 ManufacturerUri=http://www.verizon.com/ ModelUrl=http://www.verizon.com/Service={ ServiceType=urn:schemas-upnp-org:service:WANIPConnection ServiceVersion=2 ServiceId=urn:upnp-org:serviceId:WANIPConn1 ControlUri=http://192.168.1.1:37010/ctl/IPConn SCPDUri=http://192.168.1.1:37010/WANIPCn.xml } Service={ ServiceType=urn:schemas-upnp-org:service:WANIPv6FirewallControl ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:WANIPv6Firewall1 ControlUri=http://192.168.1.1:37010/ctl/IP6FCtl SCPDUri=http://192.168.1.1:37010/WANIP6FC.xml }  } Service={ ServiceType=urn:schemas-upnp-org:service:WANCommonInterfaceConfig ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:WANCommonIFC1 ControlUri=http://192.168.1.1:37010/ctl/CmnIfCfg SCPDUri=http://192.168.1.1:37010/WANCfg.xml }  } Service={ ServiceType=urn:schemas-upnp-org:service:Layer3Forwarding ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:L3Forwarding1 ControlUri=http://192.168.1.1:37010/ctl/L3F SCPDUri=http://192.168.1.1:37010/L3F.xml } Service={ ServiceType=urn:schemas-upnp-org:service:DeviceProtection ServiceVersion=1 ServiceId=urn:upnp-org:serviceId:DeviceProtection1 ControlUri=http://192.168.1.1:37010/ctl/DP SCPDUri=http://192.168.1.1:37010/DP.xml }  }
02/20 17:02:49 Trace: [mobile] [remoteconnectivity] Port Verification started due to: found UPnP WANConnectiondevice, not testing port opening because the broker is not loaded and ready.  account status: NoAccountConfigured, machine status: NeedsAccount, load status: Ready
02/20 17:02:50 Trace: [broker/httpserver] https port changed. Kicking off discovery cycle
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /proc
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /dev
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/security
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /dev/shm
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /dev/pts
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /run
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/unified
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/systemd
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/pstore
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/bpf
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/devices
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/pids
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/freezer
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/cpu,cpuacct
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/net_cls,net_prio
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/hugetlb
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/cpuset
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/perf_event
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/blkio
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/memory
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/config
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/debug
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /dev/hugepages
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /dev/mqueue
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /proc/sys/fs/binfmt_misc
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/fuse/connections
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /tmp
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /boot
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /var/lib/nfs/rpc_pipefs
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /storage
02/20 17:02:50 Trace: [volumewatcher] ev_VolumeChanged DidMount: /run/user/0
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys because it is not a /dev/sd[0-9]* (mountline: sysfs /sys sysfs rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /proc
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /proc because it is not a /dev/sd[0-9]* (mountline: proc /proc proc rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/security
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/shm
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/security because it is not a /dev/sd[0-9]* (mountline: securityfs /sys/kernel/security securityfs rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/pts
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /dev/shm because it is not a /dev/sd[0-9]* (mountline: tmpfs /dev/shm tmpfs rw,nosuid,nodev 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /dev/pts because it is not a /dev/sd[0-9]* (mountline: devpts /dev/pts devpts rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /run because it is not a /dev/sd[0-9]* (mountline: tmpfs /run tmpfs rw,nosuid,nodev,mode=755 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup because it is not a /dev/sd[0-9]* (mountline: tmpfs /sys/fs/cgroup tmpfs ro,nosuid,nodev,noexec,mode=755 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/unified
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/systemd
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/unified because it is not a /dev/sd[0-9]* (mountline: cgroup2 /sys/fs/cgroup/unified cgroup2 rw,nosuid,nodev,noexec,relatime,nsdelegate 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/systemd because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/systemd cgroup rw,nosuid,nodev,noexec,relatime,xattr,name=systemd 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/pstore
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/bpf
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/pstore because it is not a /dev/sd[0-9]* (mountline: pstore /sys/fs/pstore pstore rw,nosuid,nodev,noexec,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/devices
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/bpf because it is not a /dev/sd[0-9]* (mountline: none /sys/fs/bpf bpf rw,nosuid,nodev,noexec,relatime,mode=700 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/pids
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/devices because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/devices cgroup rw,nosuid,nodev,noexec,relatime,devices 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/pids because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/pids cgroup rw,nosuid,nodev,noexec,relatime,pids 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/freezer
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/cpu,cpuacct
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/freezer because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/freezer cgroup rw,nosuid,nodev,noexec,relatime,freezer 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/cpu,cpuacct because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/cpu,cpuacct cgroup rw,nosuid,nodev,noexec,relatime,cpu,cpuacct 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/net_cls,net_prio
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/hugetlb
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/net_cls,net_prio because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/net_cls,net_prio cgroup rw,nosuid,nodev,noexec,relatime,net_cls,net_prio 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/cpuset
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/hugetlb because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/hugetlb cgroup rw,nosuid,nodev,noexec,relatime,hugetlb 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/perf_event
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/cpuset because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/cpuset cgroup rw,nosuid,nodev,noexec,relatime,cpuset 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/perf_event because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/perf_event cgroup rw,nosuid,nodev,noexec,relatime,perf_event 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/blkio
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/blkio because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/blkio cgroup rw,nosuid,nodev,noexec,relatime,blkio 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/memory
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/config
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/cgroup/memory because it is not a /dev/sd[0-9]* (mountline: cgroup /sys/fs/cgroup/memory cgroup rw,nosuid,nodev,noexec,relatime,memory 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/config because it is not a /dev/sd[0-9]* (mountline: configfs /sys/kernel/config configfs rw,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /dev because it is not a /dev/sd[0-9]* (mountline: devtmpfs /dev devtmpfs rw,nosuid,size=4017468k,nr_inodes=1004367,mode=755 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/debug
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/hugepages
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/kernel/debug because it is not a /dev/sd[0-9]* (mountline: debugfs /sys/kernel/debug debugfs rw,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/mqueue
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /proc/sys/fs/binfmt_misc
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/fuse/connections
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /tmp
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /boot
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /var/lib/nfs/rpc_pipefs
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /dev/hugepages because it is not a /dev/sd[0-9]* (mountline: hugetlbfs /dev/hugepages hugetlbfs rw,relatime,pagesize=2M 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /var/lib/nfs/rpc_pipefs because it is not a /dev/sd[0-9]* (mountline: sunrpc /var/lib/nfs/rpc_pipefs rpc_pipefs rw,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /storage
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /storage because it is not a /dev/sd[0-9]* (mountline: /dev/mapper/VolGroup-lv_storage /storage ext4 rw,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /proc/sys/fs/binfmt_misc because it is not a /dev/sd[0-9]* (mountline: systemd-1 /proc/sys/fs/binfmt_misc autofs rw,relatime,fd=40,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=17478 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /tmp because it is not a /dev/sd[0-9]* (mountline: tmpfs /tmp tmpfs rw,nosuid,nodev 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run/user/0
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /run/user/0 because it is not a /dev/sd[0-9]* (mountline: tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=806220k,mode=700 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /sys/fs/fuse/connections because it is not a /dev/sd[0-9]* (mountline: fusectl /sys/fs/fuse/connections fusectl rw,relatime 0 0)
02/20 17:02:50 Debug: [broker/filebrowser/volumeattached] skipping /dev/mqueue because it is not a /dev/sd[0-9]* (mountline: mqueue /dev/mqueue mqueue rw,relatime 0 0)
02/20 17:02:53 Debug: [easyhttp] [5] POST to https://api.roonlabs.net/discovery/1/register returned after 109 ms, status code: 200, request body size: 1 KB
02/20 17:02:53 Trace: [inetdiscovery] registered 1 devices, 5 services
02/20 17:02:58 Debug: [easyhttp] [7] POST to https://api.roonlabs.net/device-map/1/register returned after 68 ms, status code: 200, request body size: 1 KB
02/20 17:02:58 Trace: [devicemap] device map updated
02/20 17:02:58 Debug: [easyhttp] [6] GET to https://api.roonlabs.net/push-manager/1/connect returned after 146 ms, status code: 200, request body size: 0 B
02/20 17:02:58 Debug: [push2] push connector url received from push manager: ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
02/20 17:02:58 Trace: [push2] connecting to push2 connector at ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
02/20 17:02:58 Trace: [push2] connected to push2 connector at ws://push-connector-v2-0.prd-roonlabs-1.prd.roonlabs.net/
02/20 17:03:02 Trace: [volumewatcher] ev_VolumeChanged DidUnmount: /run/user/0
02/20 17:03:02 Debug: [broker/filebrowser/volumeattached] lost mounted drive at /run/user/0, but we don't care
02/20 17:03:03 Info: [stats] 4678mb Virtual, 133mb Physical, 16mb Managed, 226 Handles, 42 Threads
02/20 17:03:18 Trace: [appupdater] initial check for updates
02/20 17:03:18 Debug: [base/updater] Checking for updates: https://updates.roonlabs.com/update/?v=2&serial=EA800738-B9F7-4AEB-A3CF-4A213929A13A&userid=&platform=linuxx64&product=RoonServer&branding=roon&curbranch=production&version=200001368&branch=production&coredeviceid=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c&deviceid=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c&osversion=Linux+5.6.13-100.sgc.fc30.x86_64&os64bit=true
02/20 17:03:18 Debug: [easyhttp] [8] POST to https://api.roonlabs.net/roonmobile/1/cores/announce returned after 17 ms, status code: 401, request body size: 633 B
02/20 17:03:18 Debug: [easyhttp] [9] GET to https://api.roonlabs.net/updates/update/?v=2&serial=EA800738-B9F7-4AEB-A3CF-4A213929A13A&userid=&platform=linuxx64&product=RoonServer&branding=roon&curbranch=production&version=200001368&branch=production&coredeviceid=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c&deviceid=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c&osversion=Linux+5.6.13-100.sgc.fc30.x86_64&os64bit=true returned after 139 ms, status code: 204, request body size: 0 B
02/20 17:03:18 Debug: [appupdater] Update not needed
02/20 17:03:18 Debug: [easyhttp] [10] POST to https://api.roonlabs.net/roonmobile/1/cores/announce returned after 18 ms, status code: 401, request body size: 633 B
02/20 17:03:18 Info: [stats] 4621mb Virtual, 136mb Physical, 17mb Managed, 226 Handles, 33 Threads
02/20 17:03:32 Trace: [volumewatcher] ev_VolumeChanged DidMount: /run/user/0
02/20 17:03:32 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run/user/0
02/20 17:03:32 Debug: [broker/filebrowser/volumeattached] skipping /run/user/0 because it is not a /dev/sd[0-9]* (mountline: tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=806220k,mode=700 0 0)
02/20 17:03:33 Info: [stats] 4605mb Virtual, 136mb Physical, 17mb Managed, 225 Handles, 29 Threads
02/20 17:03:43 Trace: [volumewatcher] ev_VolumeChanged DidUnmount: /run/user/0
02/20 17:03:43 Debug: [broker/filebrowser/volumeattached] lost mounted drive at /run/user/0, but we don't care
02/20 17:03:48 Info: [stats] 4653mb Virtual, 138mb Physical, 15mb Managed, 226 Handles, 39 Threads
02/20 17:04:03 Info: [stats] 4661mb Virtual, 133mb Physical, 11mb Managed, 225 Handles, 40 Threads
02/20 17:04:18 Info: [stats] 4597mb Virtual, 134mb Physical, 11mb Managed, 225 Handles, 28 Threads
02/20 17:04:33 Info: [stats] 4597mb Virtual, 134mb Physical, 10mb Managed, 224 Handles, 32 Threads
02/20 17:04:48 Info: [stats] 4645mb Virtual, 133mb Physical, 10mb Managed, 224 Handles, 38 Threads
02/20 17:04:58 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49191=>192.168.1.78:9332] Connected
02/20 17:04:58 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49191=>192.168.1.78:9332] Initialized Fresh Session
02/20 17:04:58 Info: [remoting/serverconnectionv2] Client connected: 192.168.1.96:49191
02/20 17:04:58 Trace: [SOOD] Adding User IP 192.168.1.96
02/20 17:04:58 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49193=>192.168.1.78:9332] Connected
02/20 17:04:58 Warn: [remoting/brokerserver] [initconn 192.168.1.96:49193=>192.168.1.78:9332] failed: System.Exception: incomplete receive
   at Sooloos.Broker.Distributed.InitConnectionV2.Go()
02/20 17:04:58 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49194=>192.168.1.78:9332] Connected
02/20 17:04:58 Warn: [remoting/brokerserver] [initconn 192.168.1.96:49194=>192.168.1.78:9332] failed: System.Exception: incomplete receive
   at Sooloos.Broker.Distributed.InitConnectionV2.Go()
02/20 17:04:58 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c BrokerName='sonictransport' }
02/20 17:04:58 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=200001368 DisplayValue='2.0 (build 1368) production' Branch='production' } }
02/20 17:05:03 Info: [stats] 4670mb Virtual, 174mb Physical, 28mb Managed, 225 Handles, 43 Threads
02/20 17:05:08 Debug: [easyhttp] [12] POST to https://api.roonlabs.net/device-map/1/register returned after 185 ms, status code: 200, request body size: 1 KB
02/20 17:05:08 Trace: [devicemap] device map updated
02/20 17:05:18 Info: [stats] 4670mb Virtual, 174mb Physical, 28mb Managed, 226 Handles, 38 Threads
02/20 17:05:33 Info: [stats] 4622mb Virtual, 174mb Physical, 29mb Managed, 226 Handles, 32 Threads
02/20 17:05:48 Info: [stats] 4654mb Virtual, 174mb Physical, 29mb Managed, 225 Handles, 40 Threads
02/20 17:06:03 Info: [stats] 4654mb Virtual, 174mb Physical, 30mb Managed, 225 Handles, 39 Threads
02/20 17:06:18 Info: [stats] 4590mb Virtual, 174mb Physical, 30mb Managed, 225 Handles, 30 Threads
02/20 17:06:28 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.1.96:49191
02/20 17:06:33 Info: [stats] 4590mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:06:37 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49198=>192.168.1.78:9332] Connected
02/20 17:06:37 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49197=>192.168.1.78:9332] Connected
02/20 17:06:37 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49198=>192.168.1.78:9332] Resumed Session
02/20 17:06:37 Trace: [remoting/remotingprotocolv2] resume send 0 messages, 0KiB
02/20 17:06:37 Warn: [remoting/brokerserver] [initconn 192.168.1.96:49197=>192.168.1.78:9332] failed: System.Exception: incomplete receive
   at Sooloos.Broker.Distributed.InitConnectionV2.Go()
02/20 17:06:41 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.1.96:49191
02/20 17:06:48 Info: [stats] 4662mb Virtual, 174mb Physical, 29mb Managed, 225 Handles, 42 Threads
02/20 17:07:03 Info: [stats] 4662mb Virtual, 174mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:07:18 Info: [stats] 4598mb Virtual, 174mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:07:34 Info: [stats] 4565mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:07:48 Trace: [broker/accounts] [heartbeat] now=2/20/2024 10:07:48 PM nextauthrefresh=2/20/2024 11:02:48 PM nextmachineallocate=2/21/2024 2:02:48 AM
02/20 17:07:49 Info: [stats] 4646mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:08:04 Info: [stats] 4646mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 38 Threads
02/20 17:08:19 Info: [stats] 4598mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:08:34 Info: [stats] 4598mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:08:49 Info: [stats] 4646mb Virtual, 174mb Physical, 30mb Managed, 225 Handles, 39 Threads
02/20 17:09:04 Info: [stats] 4646mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 38 Threads
02/20 17:09:19 Info: [stats] 4598mb Virtual, 174mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:09:30 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49202=>192.168.1.78:9332] Connected
02/20 17:09:30 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49202=>192.168.1.78:9332] Initialized Fresh Session
02/20 17:09:30 Trace: [remoting/brokerserver] killing old client from afc5af10-cb9c-49f4-8a22-91453a7088f3 because we have a new one
02/20 17:09:30 Info: [remoting/serverconnectionv2] Client connected: 192.168.1.96:49202
02/20 17:09:31 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.ConnectResponse={ BrokerId=4e495ffb-aa79-4ba3-91e6-1ae3d5407a8c BrokerName='sonictransport' }
02/20 17:09:31 Trace: [remoting/remotingserverv2] SENT NONFINAL DistributedBroker.UpdatesChangedResponse={ IsSupported=True WasJustUpdated=False Status='UpToDate' HasChangeLog=False CurrentVersion={ MachineValue=200001368 DisplayValue='2.0 (build 1368) production' Branch='production' } }
02/20 17:09:34 Info: [stats] 4654mb Virtual, 175mb Physical, 47mb Managed, 225 Handles, 41 Threads
02/20 17:09:41 Debug: [easyhttp] [13] POST to https://api.roonlabs.net/device-map/1/register returned after 135 ms, status code: 200, request body size: 1 KB
02/20 17:09:41 Trace: [devicemap] device map updated
02/20 17:09:49 Info: [stats] 4670mb Virtual, 178mb Physical, 29mb Managed, 226 Handles, 43 Threads
02/20 17:09:53 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49207=>192.168.1.78:9332] Connected
02/20 17:09:53 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49208=>192.168.1.78:9332] Connected
02/20 17:09:53 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49207=>192.168.1.78:9332] Resumed Session
02/20 17:09:53 Warn: [remoting/remotingprotocolv2] stealing a perfectly good connection...hmmm
02/20 17:09:53 Trace: [remoting/remotingprotocolv2] resume send 0 messages, 0KiB
02/20 17:09:53 Warn: [remoting/brokerserver] [initconn 192.168.1.96:49208=>192.168.1.78:9332] failed: System.Exception: incomplete receive
   at Sooloos.Broker.Distributed.InitConnectionV2.Go()
02/20 17:10:04 Info: [stats] 4670mb Virtual, 178mb Physical, 30mb Managed, 226 Handles, 42 Threads
02/20 17:10:19 Info: [stats] 4622mb Virtual, 178mb Physical, 30mb Managed, 225 Handles, 33 Threads
02/20 17:10:32 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.1.96:49202
02/20 17:10:34 Info: [stats] 4622mb Virtual, 179mb Physical, 28mb Managed, 224 Handles, 33 Threads
02/20 17:10:39 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49211=>192.168.1.78:9332] Connected
02/20 17:10:39 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49210=>192.168.1.78:9332] Connected
02/20 17:10:39 Trace: [remoting/brokerserver] [initconn 192.168.1.96:49211=>192.168.1.78:9332] Resumed Session
02/20 17:10:39 Trace: [remoting/remotingprotocolv2] resume send 0 messages, 0KiB
02/20 17:10:39 Warn: [remoting/brokerserver] [initconn 192.168.1.96:49210=>192.168.1.78:9332] failed: System.Exception: incomplete receive
   at Sooloos.Broker.Distributed.InitConnectionV2.Go()
02/20 17:10:41 Info: [remoting/serverconnectionv2] Client disconnected: 192.168.1.96:49202
02/20 17:10:49 Info: [stats] 4646mb Virtual, 180mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:11:04 Info: [stats] 4646mb Virtual, 180mb Physical, 30mb Managed, 224 Handles, 38 Threads
02/20 17:11:19 Info: [stats] 4598mb Virtual, 181mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:11:34 Info: [stats] 4598mb Virtual, 181mb Physical, 29mb Managed, 224 Handles, 31 Threads
02/20 17:11:49 Info: [stats] 4654mb Virtual, 180mb Physical, 29mb Managed, 226 Handles, 40 Threads
02/20 17:12:04 Info: [stats] 4654mb Virtual, 180mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:12:19 Info: [stats] 4614mb Virtual, 179mb Physical, 29mb Managed, 224 Handles, 31 Threads
02/20 17:12:34 Info: [stats] 4598mb Virtual, 179mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:12:48 Trace: [broker/accounts] [heartbeat] now=2/20/2024 10:12:48 PM nextauthrefresh=2/20/2024 11:02:48 PM nextmachineallocate=2/21/2024 2:02:48 AM
02/20 17:12:49 Info: [stats] 4654mb Virtual, 179mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:13:04 Info: [stats] 4654mb Virtual, 179mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:13:19 Info: [stats] 4598mb Virtual, 179mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:13:34 Info: [stats] 4598mb Virtual, 179mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:13:49 Info: [stats] 4654mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:14:04 Info: [stats] 4654mb Virtual, 178mb Physical, 30mb Managed, 224 Handles, 40 Threads
02/20 17:14:19 Info: [stats] 4582mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:14:34 Info: [stats] 4582mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:14:49 Info: [stats] 4654mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:15:04 Info: [stats] 4654mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 39 Threads
02/20 17:15:19 Info: [stats] 4598mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:15:34 Info: [stats] 4598mb Virtual, 178mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:15:49 Info: [stats] 4646mb Virtual, 178mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:16:04 Info: [stats] 4654mb Virtual, 178mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:16:19 Info: [stats] 4598mb Virtual, 178mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:16:34 Info: [stats] 4598mb Virtual, 178mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:16:49 Info: [stats] 4638mb Virtual, 178mb Physical, 30mb Managed, 225 Handles, 38 Threads
02/20 17:17:04 Info: [stats] 4638mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 38 Threads
02/20 17:17:19 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:17:34 Info: [stats] 4565mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 27 Threads
02/20 17:17:48 Trace: [broker/accounts] [heartbeat] now=2/20/2024 10:17:48 PM nextauthrefresh=2/20/2024 11:02:48 PM nextmachineallocate=2/21/2024 2:02:48 AM
02/20 17:17:49 Info: [stats] 4654mb Virtual, 176mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:18:04 Info: [stats] 4654mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 39 Threads
02/20 17:18:19 Info: [stats] 4614mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 31 Threads
02/20 17:18:34 Info: [stats] 4598mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:18:49 Info: [stats] 4646mb Virtual, 177mb Physical, 30mb Managed, 225 Handles, 39 Threads
02/20 17:19:04 Info: [stats] 4654mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:19:19 Info: [stats] 4598mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:19:34 Info: [stats] 4598mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 33 Threads
02/20 17:19:49 Info: [stats] 4638mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 38 Threads
02/20 17:20:04 Info: [stats] 4638mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 38 Threads
02/20 17:20:19 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:20:21 Trace: [volumewatcher] ev_VolumeChanged DidMount: /run/user/0
02/20 17:20:21 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run/user/0
02/20 17:20:21 Debug: [broker/filebrowser/volumeattached] skipping /run/user/0 because it is not a /dev/sd[0-9]* (mountline: tmpfs /run/user/0 tmpfs rw,nosuid,nodev,relatime,size=806220k,mode=700 0 0)
02/20 17:20:31 Trace: [volumewatcher] ev_VolumeChanged DidUnmount: /run/user/0
02/20 17:20:31 Debug: [broker/filebrowser/volumeattached] lost mounted drive at /run/user/0, but we don't care
02/20 17:20:34 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:20:49 Info: [stats] 4662mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 41 Threads
02/20 17:21:04 Info: [stats] 4662mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 40 Threads
02/20 17:21:19 Info: [stats] 4598mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:21:34 Info: [stats] 4598mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 29 Threads
02/20 17:21:49 Info: [stats] 4646mb Virtual, 177mb Physical, 30mb Managed, 224 Handles, 39 Threads
02/20 17:22:04 Info: [stats] 4646mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 39 Threads
02/20 17:22:19 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:22:34 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:22:48 Trace: [broker/accounts] [heartbeat] now=2/20/2024 10:22:48 PM nextauthrefresh=2/20/2024 11:02:48 PM nextmachineallocate=2/21/2024 2:02:48 AM
02/20 17:22:49 Info: [stats] 4654mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 40 Threads
02/20 17:23:04 Info: [stats] 4654mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 39 Threads
02/20 17:23:19 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:23:34 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads
02/20 17:23:49 Info: [stats] 4646mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 39 Threads
02/20 17:24:04 Info: [stats] 4646mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 38 Threads
02/20 17:24:19 Info: [stats] 4598mb Virtual, 177mb Physical, 29mb Managed, 224 Handles, 29 Threads