Remote can't connect to Roon Server

Mark_Turner · September 26, 2021, 11:43pm

Roon Core Machine

i7 sonicTransporter
version 1.8 build 831
remote on iPad 14.8

Networking Gear & Setup Details

Actiontec T2200h modem/router
EtherRegen switch

Connected Audio Devices

router =>(ethernet cable) sonicTransporter
sonicTransporter =>( ethernet cable) etherRegen
etherRegen => (ethernet cable) UltraRendu
UltraRendu=>(USB) Auralic vega

Number of Tracks in Library

5000 tracks

Description of Issue

I updated version 1.8 and played successfully for an hour. I came back two days later and can’t connect to server. On opening remote it tells me to choose a core (given with correct ip address) and login. Then tells me I’m already logged and no connection occurs. Nothing has changed in my network and the server is still online. I reinstalled Roon remote and it didn’t help ( I accidentally installed Roon essentials first and it said it detected roon core on my system). I re-installed core and it didn’t help. Server log attached, please help.

09/26 17:12:06 Info: Starting RoonServer v1.8 (build 831) stable on linuxx64 
09/26 17:12:06 Trace: Checking if we are already running 
09/26 17:12:06 Warn: get lock file path: /tmp/.rnsems0-roon 
09/26 17:12:06 Trace: Nope, we are the only one running 
09/26 17:12:06 Info: Is 64 bit? True 
09/26 17:12:06 Info: Command Line Argument: -watchdogport=43433 
09/26 17:12:06 Trace: [childprocess] using .NET child process 
09/26 17:12:06 Trace: [realtime] fetching time from NTP server 
09/26 17:12:06 Trace: [orbit] init seq=0, pending=0, spcount=7, uidcount=2 
09/26 17:12:06 Info: [broker] starting e43de8d3-ff1b-4c9b-98e6-d67633bdba7d 
09/26 17:12:06 Warn: [realtime] failed to get time: Could not resolve host 'pool.ntp.org' 
09/26 17:12:06 Trace: [httpcache] loaded 0 cache entries from /var/roon/RoonServer/Cache/httpcache_2.db, current: 0mb / 128mb 
09/26 17:12:06 Info: [broker/locations] adding storage location: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb 
09/26 17:12:06 Info: [broker/locations] media availability: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb is online 
09/26 17:12:06 Info: [broker/locations] adding storage location: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8 
09/26 17:12:06 Info: [broker/locations] media availability: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8 is online 
09/26 17:12:06 Info: [broker/locations] adding storage location: Offline:Name=Offline:Id=f1e4b43f-f643-47ba-b875-fd93b32a6006 
09/26 17:12:06 Debug: [broker/locations/migration] start, propname: location_automounts 
09/26 17:12:06 Debug: [broker/locations] load pre 1.3 automounts, strlist: 
09/26 17:12:06 Info: [broker/locations] adding storage location: DefaultMusicFolder:Name=Music Folder:Id=29bdcae6-b5fd-d666-27bf-a1f6afceabac 
09/26 17:12:06 Debug: [broker/locations] write pre 1.3 automounts, strlist: 
09/26 17:12:06 Info: [media] resolved oop decoder to /usr/bin/ffmpeg 
09/26 17:12:06 Trace: [brokerserver] Enabling broker server 
09/26 17:12:06 Info: [broker/distributed] HTTP server listening on port 9100 
09/26 17:12:06 Trace: [brokerserver] Enabling https broker server 
09/26 17:12:06 Info: [broker/distributed] HTTPS server listening on port 9315 
09/26 17:12:06 Trace: [broker/backups] initializing 
09/26 17:12:06 Trace: [broker/accounts] Data updated. AccountStatus=NoAccountConfigured MachineStatus=NeedsAccount UserId= 
09/26 17:12:06 Trace: [broker/accounts] [heartbeat] now=9/26/2021 9:12:06 PM nextauthrefresh=9/26/2021 9:07:06 PM nextmachineallocate=9/26/2021 9:12:06 PM 
09/26 17:12:06 Trace: [broker/accounts] doing auth refresh, next=9/26/2021 9:07:06 PM 
09/26 17:12:06 Trace: [broker/accounts] doing machine allocate, next=9/27/2021 1:12:06 AM 
09/26 17:12:06 Trace: [inetdiscovery] added device broker/e43de8d3-ff1b-4c9b-98e6-d67633bdba7d in addr:__ADDR__ 
09/26 17:12:06 Info: [libraryapi] loaded first run time 9/24/2021 11:29:35 PM 
09/26 17:12:06 Trace: [devicedb] initializing 
09/26 17:12:07 Info: [raatserver] [runner] Start or Connect... 
09/26 17:12:07 Info: [raatserver] [runner] Start or Connect... /opt/RoonServer/Appliance/RAATServer 
09/26 17:12:07 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonServer/Appliance/RAATServer 
09/26 17:12:07 Trace: [broker/services] refreshing metadata URL bits 
09/26 17:12:07 Info: [broker/services] using URL override for identifier: https://identifier.roonlabs.net/identifier/ 
09/26 17:12:07 Info: [broker/services] using URL override for imagecache: https://imagecache.roonlabs.net/im/ 
09/26 17:12:07 Info: [broker/services] using URL override for swim: https://swim.roonlabs.net/ 
09/26 17:12:07 Info: [broker/services] using URL override for qobuzservice: https://qobuz.roonlabs.net/ 
09/26 17:12:07 Info: [broker/services] using URL override for tidalservice: https://tidal.roonlabs.net/ 
09/26 17:12:07 Info: connected to watchdogport: 43433 
09/26 17:12:07 Debug: [broker/filebrowser/nassharemaker] rebuildsymlinks 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys 
09/26 17:12:07 Info: [raatserver] [runner] Status: Started 
09/26 17:12:07 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) 
09/26 17:12:07 Info: [broker/messaging] initializing 
09/26 17:12:07 Info: [metadata] initializing 
09/26 17:12:07 Info: [music] first pass through media thread is done 
09/26 17:12:07 Info: [transport] initializing 
09/26 17:12:07 Trace: [devicedb] refreshing, etag=W/"206090-17c0d2c8627" 
09/26 17:12:07 Trace: [ipaddresses] enumerating addresses 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED lo: not up 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth0: no ipv4 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth1: no ipv4 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth2: no ipv4 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth3: no ipv4 
09/26 17:12:07 Trace: [ipaddresses] SKIPPED br0: no ipv4 
09/26 17:12:07 Trace: [broker/accounts] doing auth refresh in EnsureAuthReady, next=9/26/2021 10:12:07 PM 
09/26 17:12:07 Warn: [auth] EnsureAuthReady failed: Result[Status=NotLoggedIn] 
09/26 17:12:07 Trace: [devicedb] builtin device db found at /opt/RoonServer/Appliance/devicedb-prod.zip 
09/26 17:12:07 Trace: [devicedb] cached device db found at /var/roon/RoonServer/Cache/devicedb-prod.zip 
09/26 17:12:07 Info: [broker/dropbox] no account configured 
09/26 17:12:07 Trace: [devicedb] loading device db at /var/roon/RoonServer/Cache/devicedb-prod.zip 
09/26 17:12:07 Info: [remoting] loaded protocol hash e489d012e57c96f99d2795d6ba715e7a76198f2e from /opt/RoonServer/Appliance/Roon.Broker.Api.Remote.dll 
09/26 17:12:07 Info: [broker/distributed] Remoting server listening on port 9101 
09/26 17:12:07 Trace: [inetdiscovery] added service com.roonlabs.roon.broker.tcp for device broker/e43de8d3-ff1b-4c9b-98e6-d67633bdba7d 
09/26 17:12:07 Trace: [inetdiscovery] added service com.roonlabs.roon.broker.http for device broker/e43de8d3-ff1b-4c9b-98e6-d67633bdba7d 
09/26 17:12:07 Trace: [geoip] GET https://geoip.roonlabs.net/geoip/1/lookup 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /proc 09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /proc 
09/26 17:12:07 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) 
09/26 17:12:07 Info: [loadstatus] IsLibraryLoading True => False 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] skipping /dev because it is not a /dev/sd[0-9]* (mountline: devtmpfs /dev devtmpfs rw,nosuid,size=4010168k,nr_inodes=1002542,mode=755 0 0) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/security 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/shm 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/pts 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /run 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/unified 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/systemd 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/pstore 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/bpf 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/cpu,cpuacct 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/net_cls,net_prio 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/perf_event 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/blkio 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/freezer09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/freezer 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/pids 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/cpuset 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/hugetlb 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/devices 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/fs/cgroup/memory 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/config 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at / 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/hugepages 
09/26 17:12:07 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) 
09/26 17:12:07 Warn: Error in web request https://geoip.roonlabs.net/geoip/1/lookup: NetworkError (Error: NameResolutionFailure) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /sys/kernel/debug 
09/26 17:12:07 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) 
09/26 17:12:07 Warn: [easyhttp] [2] Get https://messaging.roonlabs.net/1/api/messages/ web exception without response: Error: NameResolutionFailure 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /dev/mqueue 
09/26 17:12:07 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) 
09/26 17:12:07 Critical: scx: System.ArgumentNullException: Value cannot be null. 
Parameter name: source 
at System.Linq.Enumerable.Select[TSource,TResult] (System.Collections.Generic.IEnumerable`1[T] source, System.Func`2[T,TResult] selector) [0x0000d] in <7f6b4a389aed488594a54f15a4927c5a>:0 
at Sooloos.Broker.Messaging.Module._maybe_update_from_cache (Base.JDictionary cache_jdic) [0x00073] in <62b21a183a274b248e6366808e4a08e7>:0 
at System.Runtime.CompilerServices.AsyncMethodBuilderCore+<>c.b__7_0 (System.Object state) [0x00000] in :0 at System.Runtime.CompilerServices.AsyncMethodBuilderCore+<>c.b__7_0 (System.Object state) [0x00000] in :0 
at Sooloos.SynchronizationContextThread._Dispatch (Sooloos.SynchronizationContextThread+SendOrPostWrapper& ret) [0x00012] in <11073c9a2e2d4614af4b2a7f4d419d45>:0 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /proc/sys/fs/binfmt_misc 
09/26 17:12:07 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=50,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=19935 0 0) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /tmp 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] skipping /tmp because it is not a /dev/sd[0-9]* (mountline: tmpfs /tmp tmpfs rw,nosuid,nodev 0 0) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /boot 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /var/lib/nfs/rpc_pipefs 
09/26 17:12:07 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) 
09/26 17:12:07 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /storage 
09/26 17:12:07 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) 
09/26 17:12:07 Info: [stats] 2046mb Virtual, 102mb Physical, 21mb Managed, 0 Handles, 28 Threads 
09/26 17:12:07 Trace: [devicedb] loaded 129 vendors, 750 products from devicedb with timestamp 9/22/2021 11:03:13 AM 
09/26 17:12:07 Info: [loadstatus] IsTransportLoading True => False 
09/26 17:12:07 Info: [loadstatus] IsStartup True => False 
09/26 17:12:07 Info: [roonapi] initializing 
09/26 17:12:07 Warn: [easyhttp] [1] Get https://devicedb.roonlabs.net/1/devicedb-prod.zip web exception without response: Error: NameResolutionFailure 
09/26 17:12:07 Trace: [inetdiscovery] added service com.roonlabs.roon.api.tcp for device broker/e43de8d3-ff1b-4c9b-98e6-d67633bdba7d 
09/26 17:12:07 Trace: [inetdiscovery] added service com.roonlabs.roon.api.http for device broker/e43de8d3-ff1b-4c9b-98e6-d67633bdba7d 
09/26 17:12:07 Info: [roonapi] listening on port 9150 
09/26 17:12:07 Warn: [devicedb] While refreshing, status: 999, body: System.Net.WebException: Error: NameResolutionFailure 
at System.Net.WebConnection.Connect (System.Net.WebOperation operation, System.Threading.CancellationToken cancellationToken) [0x00044] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Net.WebConnection.InitConnection (System.Net.WebOperation operation, System.Threading.CancellationToken cancellationToken) [0x000cc] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Net.WebOperation.Run () [0x0009a] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Net.WebCompletionSource`1[T].WaitForCompletion () [0x00094] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Net.HttpWebRequest.RunWithTimeoutWorker[T] (System.Threading.Tasks.Task`1[TResult] workerTask, System.Int32 timeout, System.Action abort, System.Func`1[TResult] aborted, System.Threading.CancellationTokenSource cts) [0x000f8] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Net.HttpWebRequest.EndGetResponse (System.IAsyncResult asyncResult) [0x00020] in <9f7747ca7df742bc9cc8f94c1fafe024>:0 
at System.Threading.Tasks.TaskFactory`1[TResult].FromAsyncCoreLogic (System.IAsyncResult iar, System.Func`2[T,TResult] endFunction, System.Action`1[T] endAction, System.Threading.Tasks.Task`1[TResult] promise, System.Boolean requiresSynchronization) [0x0000f] in :0 
--- End of stack trace from previous location where exception was thrown ---

MamaTried · September 27, 2021, 4:25pm

It would appear from the following in your trace that you have no defined internet information.

09/26 17:12:06 Warn: [realtime] failed to get time: Could not resolve host ‘pool.ntp.org’

09/26 17:12:07 Trace: [ipaddresses] enumerating addresses
09/26 17:12:07 Trace: [ipaddresses] SKIPPED lo: not up
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth0: no ipv4
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth1: no ipv4
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth2: no ipv4
09/26 17:12:07 Trace: [ipaddresses] SKIPPED eth3: no ipv4
09/26 17:12:07 Trace: [ipaddresses] SKIPPED br0: no ipv4

09/26 17:12:07 Warn: Error in web request https://geoip.roonlabs.net/geoip/1/lookup: NetworkError (Error: NameResolutionFailure)

‘ip a’
this should tell you what your internet info is.

Mark_Turner · September 29, 2021, 6:14pm

Sorry I took so long to respond. My modem broke and had to be replaced. Unfortunately it didn’t change my original problem. I see the IP address issue but have a question. I access the server through two pieces of software: Roon Remote/Roon Core and Sonictransporter web browser/OS. The latter has no problem finding and talking to the server but Roon can’t. Doesn’t that suggest a Roon issue? Either way, any thought on next steps for me? Thanks.

BlackJack · September 29, 2021, 11:15pm

It’s probably using IPv6 exclusively. Note: Roon is complaining about “no ipv4”.
You can try to use IPv4 instead. Talk to your Internet service provider about this if needed. If you need help with the network settings of the sonicTransporter then you should contact Small Green Computer.

Mark_Turner · September 29, 2021, 11:38pm

Thanks for the suggestion. The perplexing thing is that I have used this setup successfully for years. I do have a new development, though. About thirty seconds after getting the “no ipv4” line in the log, I now get a line saying something changed and then the connection is established. However, the ip address is one digit different than the server and the one shown in remote as the server available for connection and the sonicTransporter. So, the server is communication with roon labs I can’t talk to it from my iPad.Any thoughts on that? Thanks.

dylan · October 4, 2021, 7:35pm

Hi @Mark_Turner,

I also see a lot of DNS errors in your logs — Can you try changing the DNS you’re using on your router?

beka · October 13, 2021, 7:49pm

Hey @Mark_Turner,

I’m just following up on Dylan’s last post - I wanted to see if the proposed solution helped, or, if we can help with anything else

Mark_Turner · October 14, 2021, 7:58pm

Hi Rebeka,

The problem seemed to be my server making a connection to Roon because a DNS issue. Unfortunately, my IPS won’t let me change the DNS server as suggested. Today, however, after thee same problem, I let it run alit longer and the connection was made.
10/14 15:30:37 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
10/14 15:30:37 Trace: [push] connecting to 35.196.114.77:9204
10/14 15:30:37 Trace: [push] connected
MY ID was established, the library downloaded and the server saw the client ipads. Then
10/14 15:31:05 Warn: Error in web request https://metadataserver.roonlabs.net/md/4/updategenres?uid=a53acdf8-9be9-4256-9190-2f5dec4f74e6&lid=&token=d38877f4-6f23-40a4-8ed5-59282194ad6b: NetworkError (The remote server returned an error: (403) Forbidden.)
Finally:
10/14 15:31:07 Info: [music/storage] disabling library
10/14 15:31:07 Critical: scx: System.NullReferenceException: Object reference not set to an instance of an object
But roon was still connected.
I hope I got all of the useful log lines. Does this tell s anything new? Thanks

Mark

jamie · June 29, 2022, 7:37pm

A post was merged into an existing topic: RoonServer Stuck Initializing on new Mac Installation, Logs Showing Critical Exception [Ticket In]