Roon not identifying newly added tracks

Michael_Bade · March 21, 2020, 10:46pm

Computer and Music Server

2013 Mac Pro 64 GB workstation (for adding music), music server: Sonictransporter i7, 2TB SSD

Network Details (Including networking gear model/manufacturer and if on WiFi/Ethernet)

Sonore UltraRendu, Netgear ethernet switches

Audio Devices (Specify what device you’re using and its connection type - USB/HDMI/etc.)

Lyngdord TDAI 2170 (amp/Dac), USB connection to UltraRendu

Description Of Issue

I added 6 FLAC albums to the SonicTransporter just now. In the past, Roon has identified FLAC albums just fine - I have a number of them. The new ones were downloaded from Bandcamp. Roon has added 130 tracks but cannot identify any of them, even though they are all correctly labeled and such. Is there a way to do this manually? Or alternatively, a way to direct Roon to the Bandcamp website? Other solution?

Notice that the dialog box says that Roon needs a software update. I updated Roon before I did any of this, to Version 1.7 Build 528. The About Roon page says this is the latest version and build.

dylan · March 22, 2020, 2:24pm

Hi @Michael_Bade,

Is there any change here after a reboot of the Core machine?

Michael_Bade · August 10, 2020, 4:51am

Sorry Dylan, I know it has been months but I got caught up in shelter-in-place and working from home.

I have rebooted the Core machine several times. This has had no lasting effect. Sometimes it helps downloaded or ripped material to enter the library. Tidal and Qobuz are another matter.

It is now almost impossible to add music from Tidal or Qobuz. There is an error message that appears when I click on the little spinning circular icon in the upper left of the Roon screen, which says:

"Adding Music To Library: Of 204 tracks, 204 addd, 0 identified

Adding music to your library involves locating your music files and clumping them together into albums. Then Roon identifies the metadata for your albums, and retrieves reviews, biographies, lyrics, credits, photos, and other metadata.

! Metadata Improver: Halted. Roon needs a software update. A software update is required before your music can be matched up with great cover art, lyrics, photos, and more."

The same happens when I rip CDs, or download purchased digital files. I can get these to be added if I reboot the Roon Server on my Sonic Transporter by logging into the Sonic Transporter and triggering the reboot. I should not have t go to the rigamarole of rebooting the Roon Server every time I add something, however.

Needless to say, I am running Roon 1.7 Build 571, self-described as the latest version.

joel · August 10, 2020, 8:34am

Tagging @dylan.

nuwriy · August 12, 2020, 3:47pm

Hello @Michael_Bade, and thanks for the update! When you reboot the core, does the metadata improver message go away or does it immediately return?

Michael_Bade · August 12, 2020, 4:20pm

Rebooting the Core only takes care of the immediate metadata backlog. After reboot, if I add Tidal/Qobuz links or rip more CDs I have to reboot the core again. It seems that rebooting the Core is the only way to process metadata.

Also, when I add a tidal/Qobuz link Roon goes into a loop and doesn’t immediately tell me that something has been added. I have to click on ‘Add to Library’, back out of the selection I am trying to add, select it again, and then use ‘Identify Album’ in the Edit dropdown and select the image of the CD/LP cover to add it. Then the metadata thing starts and the core reboot is needed. I have been a Roon user for years and this never happened before March or so, when I posted the first message.

nuwriy · August 14, 2020, 8:27pm

Hello @Michael_Bade,

I’d like to enable diagnostics for your account and get a report over to the team regarding this issue. Could you please do the following as a test so I can complete the report? Thanks!

Import something from TIDAL or Qobuz
Please provide a timestamp and album name when you do this
Reboot your machine, and please provide another timestamp

From there, I’ll enable diagnostics and we’ll send this info over to the team.

Michael_Bade · August 15, 2020, 8:02pm

First, I rebooted the Roon Server. There were 209 files (‘song’ tracks) that were listed as missing metadata. After reboot the list went away. The imported albums/CDs had blue stripe flags on them indicating newly imported, as they should.

Next, at 12:40 PST (Pacific Standard Time) I imported ‘Valentine’, Bill Frisell’s new album, and ‘In A Dream’ by Paul Motian.

Valentine is 88/24
In A Dream is 44.1/16

Both were purchased and downloaded from Qobuz. I copied these to my SonicTransporter Music file. Both showed up immediately in Roon. So - purchases seem to work at this point.

Next, at 12:56 PST I imported Baida by Ralph Alessi - which showed up correctly. All these were done at my desktop computer, listed in Settings/About as my Remote.

The times I was not able to import correctly were times I used my laptop.

I will try to upload the Roon Server Diagnostic files as well. The version that shows all of the above activity is timestamped 1:01 PM PST.

Michael_Bade · August 15, 2020, 8:11pm

Here are the Roon Server Diagnostics. I could not open the file so I copied and pasted (sorry for the length). I had to cut it down to a small enough number of characters to post (the original was >557,000 characters).

08/15 15:22:03 Info: Starting RoonServer v1.7 (build 571) stable on linuxx64
08/15 15:22:03 Trace: Checking if we are already running
08/15 15:22:03 Warn: get lock file path: /tmp/.rnsems0-roon
08/15 15:22:03 Trace: Nope, we are the only one running
08/15 15:22:03 Info: Is 64 bit? True
08/15 15:22:03 Trace: [orbit] init seq=1950, pending=78, spcount=7, uidcount=2
08/15 15:22:03 Info: Command Line Argument: -watchdogport=43481
08/15 15:22:03 Trace: [childprocess] using .NET child process
08/15 15:22:03 Trace: [realtime] fetching time from NTP server
08/15 15:22:03 Info: [broker] starting f171268a-4354-4f44-a329-9c8e238a4aaa
08/15 15:22:03 Trace: [realtime] Got time from NTP: 8/15/2020 7:22:03 PM (3806508123865ms)
08/15 15:22:03 Trace: [realtime] Updated clock skew to -00:00:00.0456580 (-45.658ms)
08/15 15:22:04 Trace: [httpcache] loaded 3303 cache entries from /var/roon/RoonServer/Cache/httpcache_2.db, current: 127mb / 128mb
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /
08/15 15:22:04 Debug: [broker/filebrowser/nassharemaker] rebuildsymlinks
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /boot
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /boot/efi
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /var/lib/docker/devicemapper
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] skipping /var/lib/docker/devicemapper because it is not a /dev/sd[0-9]* (mountline: /dev/nvme0n1p4 /var/lib/docker/devicemapper ext4 rw,relatime,data=ordered 0 0)
08/15 15:22:04 Info: [broker/locations] adding storage location: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb
08/15 15:22:04 Info: [broker/locations] media availability: Internet:Name=Internet Media:Id=e268f098-04c4-4e65-af3f-38ba3c3fcecb is online
08/15 15:22:04 Info: [broker/locations] adding storage location: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8
08/15 15:22:04 Info: [broker/locations] media availability: MetadataService:Name=Metadata Service:Id=13769258-b70b-4243-b1d6-bd46e8257ba8 is online
08/15 15:22:04 Info: [broker/locations] adding storage location: Offline:Name=Offline:Id=f1e4b43f-f643-47ba-b875-fd93b32a6006
08/15 15:22:04 Debug: [broker/locations/migration] start, propname: loc_29bdcae6b5fdd66627bfa1f6afceabac
08/15 15:22:04 Debug: [broker/locations] Migrating UUID based storage location, id: loc_29bdcae6b5fdd66627bfa1f6afceabac, version: 2
08/15 15:22:04 Debug: [broker/locations/migration] start, propname: location_automounts
08/15 15:22:04 Debug: [broker/locations] load pre 1.3 automounts, strlist:
08/15 15:22:04 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from sysfs
08/15 15:22:04 Trace: [storage] [directory] directorystorage init, location(/storage, dirwatcher is available: True)
08/15 15:22:04 Trace: [storage] [directory] init just before reorg delete thread, path: /storage
08/15 15:22:04 Info: [broker/locations] storage location backend created and attached (DefaultMusicFolder:Name=Music Folder:Id=29bdcae6-b5fd-d666-27bf-a1f6afceabac)
08/15 15:22:04 Trace: [storage] [directory] temp directory cleared, path: /storage/.tmp
08/15 15:22:04 Info: [broker/locations] adding storage location: DefaultMusicFolder:Name=Music Folder:Id=29bdcae6-b5fd-d666-27bf-a1f6afceabac
08/15 15:22:04 Info: [broker/locations] media availability: DefaultMusicFolder:Name=Music Folder:Id=29bdcae6-b5fd-d666-27bf-a1f6afceabac is online
08/15 15:22:04 Debug: [broker/locations] write pre 1.3 automounts, strlist:
08/15 15:22:04 Info: [media] resolved oop decoder to /usr/bin/ffmpeg
08/15 15:22:04 Trace: [brokerserver] Enabling broker server
08/15 15:22:04 Info: [broker/distributed] HTTP server listening on port 9100
08/15 15:22:04 Trace: [broker/backups] initializing
08/15 15:22:04 Info: [broker] is licensed for userid 7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:04 Info: [stats] 1457mb Virtual, 164mb Physical, 19mb Managed, 0 Handles, 21 Threads
08/15 15:22:04 Info: [clientdata] initializing with /var/roon/RoonServer/Database/Core/7cd10fb26cd6498d8c24b5976675201c/clientdata.db
08/15 15:22:04 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:04 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:04 Trace: [broker/accounts] [heartbeat] now=8/15/2020 7:22:04 PM nextauthrefresh=8/15/2020 7:17:04 PM nextmachineallocate=8/15/2020 7:22:04 PM
08/15 15:22:04 Trace: [broker/accounts] doing auth refresh, next=8/15/2020 7:17:04 PM
08/15 15:22:04 Trace: [broker/accounts] refreshing account info for email=‘michael.bade@calochortus.net’ userid=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:04 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
08/15 15:22:04 Trace: [fiveaccountserver] BODY token=72d2dc5b-267b-401d-b10f-fcdde5b6647b
08/15 15:22:04 Trace: [broker/accounts] doing machine allocate, next=8/15/2020 11:22:04 PM
08/15 15:22:04 Trace: [broker/accounts] Updating Machine Allocation machineid=0b63a16f-c27a-e464-950f-b55da0ea4612 hwserial= machinename=sonictransport machinetype=Server
08/15 15:22:04 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/machineallocate
08/15 15:22:04 Trace: [fiveaccountserver] BODY token=72d2dc5b-267b-401d-b10f-fcdde5b6647b&machine=0b63a16f-c27a-e464-950f-b55da0ea4612&name=sonictransport&type=Server
08/15 15:22:04 Info: [clientdata] initializing with /var/roon/RoonServer/Database/Core/7cd10fb26cd6498d8c24b5976675201c/clientdata.db
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /proc
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /dev
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/security
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /dev/shm
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /dev/pts
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /run
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/systemd
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/pstore
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/firmware/efi/efivars
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/hugetlb
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/freezer
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/pids
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/cpu,cpuacct
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/cpuset
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/blkio
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/net_cls,net_prio
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/devices
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/memory
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/fs/cgroup/perf_event
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/config
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /dev/hugepages
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /dev/mqueue
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /sys/kernel/debug
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /proc/sys/fs/binfmt_misc
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /tmp
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /boot
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /boot/efi
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /var/lib/nfs/rpc_pipefs
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /var/lib/docker/devicemapper
08/15 15:22:04 Debug: [volumewatcher] ev_VolumeChanged DidMount: /storage
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] initial listing found drive mounted at /storage
08/15 15:22:04 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,data=ordered 0 0)
08/15 15:22:04 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from proc
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /proc
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/security
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/shm
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] skipping /dev because it is not a /dev/sd[0-9]* (mountline: devtmpfs /dev devtmpfs rw,nosuid,size=3999176k,nr_inodes=999794,mode=755 0 0)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/pts
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /run
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/systemd
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/pstore
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/firmware/efi/efivars
08/15 15:22:04 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)
08/15 15:22:04 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,release_agent=/usr/lib/systemd/systemd-cgroups-agent,name=systemd 0 0)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] skipping /sys/firmware/efi/efivars because it is not a /dev/sd[0-9]* (mountline: efivarfs /sys/firmware/efi/efivars efivarfs rw,nosuid,nodev,noexec,relatime 0 0)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/hugetlb
08/15 15:22:04 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)
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/freezer
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/pids
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/cpu,cpuacct
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/cpuset
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/blkio
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/net_cls,net_prio
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/devices
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/memory
08/15 15:22:04 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)
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/fs/cgroup/perf_event
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/config
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/hugepages
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /dev/mqueue
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /sys/kernel/debug
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /proc/sys/fs/binfmt_misc
08/15 15:22:04 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)
08/15 15:22:04 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=38,pgrp=1,timeout=0,minproto=5,maxproto=5,direct,pipe_ino=313 0 0)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /tmp
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /boot
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] skipping /tmp because it is not a /dev/sd[0-9]* (mountline: tmpfs /tmp tmpfs rw,nosuid,nodev 0 0)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /boot/efi
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /var/lib/nfs/rpc_pipefs
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /var/lib/docker/devicemapper
08/15 15:22:04 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)
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] found newly mounted drive at /storage
08/15 15:22:04 Debug: [broker/filebrowser/volumeattached] skipping /var/lib/docker/devicemapper because it is not a /dev/sd[0-9]* (mountline: /dev/nvme0n1p4 /var/lib/docker/devicemapper ext4 rw,relatime,data=ordered 0 0)
08/15 15:22:04 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,data=ordered 0 0)
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from devtmpfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from securityfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from devpts
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from pstore
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from efivarfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from configfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from hugetlbfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from mqueue
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from debugfs
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from systemd-1
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from sunrpc
08/15 15:22:05 Info: [libraryapi] loaded first run time 5/7/2019 7:52:28 PM
08/15 15:22:05 Warn: [linuxmount/pinfo] coudn’t ascertain disk block device from /dev/mapper/VolGroup-lv_storage
08/15 15:22:05 Trace: [devicedb] initializing
08/15 15:22:05 Trace: [devicedb] refreshing, etag=W/“18c837-173e45f8c5b”
08/15 15:22:05 Trace: [devicedb] builtin device db found at /opt/RoonServer/Appliance/devicedb-prod.zip
08/15 15:22:05 Trace: [devicedb] cached device db found at /var/roon/RoonServer/Cache/devicedb-prod.zip
08/15 15:22:05 Trace: [devicedb] loading device db at /var/roon/RoonServer/Cache/devicedb-prod.zip
08/15 15:22:05 Trace: [devicedb] loaded 93 vendors, 531 products from devicedb with timestamp 8/12/2020 8:32:13 PM
08/15 15:22:05 Info: [raatserver] [runner] Start or Connect…
08/15 15:22:05 Info: [raatserver] [runner] Start or Connect… /opt/RoonServer/Appliance/RAATServer
08/15 15:22:05 Info: ConnectOrStartAndWaitForExit RAATServer, path: /opt/RoonServer/Appliance/RAATServer
08/15 15:22:05 Trace: [broker/services] refreshing metadata URL bits
08/15 15:22:05 Info: [broker/services] using URL override for metadata: https://metadataserver.roonlabs.net/md/
08/15 15:22:05 Info: [broker/services] using URL override for identifier: https://identifier.roonlabs.net/identifier/
08/15 15:22:05 Info: [broker/services] using URL override for imagecache: https://imagecache.roonlabs.net/im/
08/15 15:22:05 Info: [broker/services] using URL override for swim: https://swim.roonlabs.net/
08/15 15:22:05 Info: [broker/services] using URL override for metadataservice2: https://metadata.roonlabs.net/
08/15 15:22:05 Info: [broker/services] using URL override for qobuzservice: https://qobuz.roonlabs.net/
08/15 15:22:05 Info: [broker/services] using URL override for tidalservice: https://tidal.roonlabs.net/
08/15 15:22:05 Info: [broker/services] using URL override for searchservice: https://search.roonlabs.net/
08/15 15:22:05 Info: [raatserver] [runner] Status: Started
08/15 15:22:05 Info: [broker/dropbox] no account configured
08/15 15:22:05 Info: connected to watchdogport: 43481
08/15 15:22:05 Info: [remoting] loaded protocol hash 635f572cd00df305e1b3093906d24e04067f5f53 from /opt/RoonServer/Appliance/Roon.Broker.Api.Remote.dll
08/15 15:22:05 Info: [broker/distributed] Remoting server listening on port 9101
08/15 15:22:05 Trace: [geoip] GET https://accounts5.roonlabs.com/geoip/1/lookup
08/15 15:22:05 Info: [music/database] opened database. migration version = 8 current version = 8
08/15 15:22:05 Info: [playlistdb] opened. migration version = 1 current version = 1
08/15 15:22:05 Info: [identification] Starting
08/15 15:22:05 Info: [additionalimages] starting
08/15 15:22:05 Trace: [taste] loaded ProfileId=63:1:f7a0941a-a1d4-46d1-a145-d7dc372cf606 IsDirty=True LastUpdate=8/10/2020 5:41:10 AM LastSummaryHash=cf1f05b7:ac492e45:c20addc4:46e69ab:ddf73c6f
08/15 15:22:05 Debug: [easyhttp] [1] GET to https://devicedb.roonlabs.net/1/devicedb-prod.zip returned after 316 ms, status code: 304
08/15 15:22:05 Trace: [devicedb] Not Modified. Nothing to do
08/15 15:22:05 Trace: Successful POST response from https://accounts5.roonlabs.com/accounts/3/machineallocate
08/15 15:22:05 Trace: [fiveaccountserver] GOT {“status”:“Success”,“licenseid”:“d77f3d79-0b3a-404a-aaa8-978d1dce54ca”}
08/15 15:22:05 Trace: Successful POST response from https://accounts5.roonlabs.com/accounts/3/login
08/15 15:22:05 Trace: [fiveaccountserver] GOT {“status":“Success”,“userid”:“7cd10fb2-6cd6-498d-8c24-b5976675201c”,“token”:“72d2dc5b-267b-401d-b10f-fcdde5b6647b”,“expiration”:30,“email”:"michael.bade@calochortus.net”,“groups”:[]}
08/15 15:22:05 Trace: [geoip] GOT {“country”:“US”,“state”:“CA”,“city”:“San Francisco”,“postalcode”:“94122”,“latitude”:37.7607,“longitude”:-122.4842,“ipaddress”:“23.114.182.198”,“status”:“Success”}
08/15 15:22:05 Info: [library] Queueing initial load of 53504 tracks + 938 auxfiles
08/15 15:22:05 Trace: [music/genres] loading genres (contenthash=6793425feb4b7ed93a46e1808bed78e2)
08/15 15:22:05 Info: GENRE: name,level,is broad?,is holiday?,is stratified?,is directonly?
08/15 15:22:05 Trace: [music/genres] load complete. 1237 genres loaded.
08/15 15:22:05 Trace: [music/genres] fetching genre list from remote (contenthash=6793425feb4b7ed93a46e1808bed78e2)
08/15 15:22:05 Trace: [broker/accounts] doing auth refresh in EnsureAuthReady, next=8/15/2020 8:22:05 PM
08/15 15:22:05 Trace: [broker/accounts] refreshing account info for email=‘michael.bade@calochortus.net’ userid=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:05 Trace: [fiveaccountserver] POST https://accounts5.roonlabs.com/accounts/3/login
08/15 15:22:05 Trace: [fiveaccountserver] BODY token=72d2dc5b-267b-401d-b10f-fcdde5b6647b
08/15 15:22:05 Info: [metadata] initializing
08/15 15:22:05 Info: [broker/locations] adding storage location: Tidal:Name=TIDAL:Id=a2bc918b-81fb-abc3-f24b-19bcfed910a2
08/15 15:22:05 Info: [broker/locations] updating location Tidal:Name=TIDAL:Id=a2bc918b-81fb-abc3-f24b-19bcfed910a2
08/15 15:22:05 Trace: Successful POST response from https://accounts5.roonlabs.com/accounts/3/login
08/15 15:22:05 Trace: [fiveaccountserver] GOT {“status":“Success”,“userid”:“7cd10fb2-6cd6-498d-8c24-b5976675201c”,“token”:“72d2dc5b-267b-401d-b10f-fcdde5b6647b”,“expiration”:30,“email”:"michael.bade@calochortus.net”,“groups”:[]}
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Trace: [broker/accounts] Machine Allocation Succeeded
08/15 15:22:06 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=72d2dc5b-267b-401d-b10f-fcdde5b6647b
08/15 15:22:06 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=72d2dc5b-267b-401d-b10f-fcdde5b6647b&machineid=0b63a16f-c27a-e464-950f-b55da0ea4612
08/15 15:22:06 Trace: [broker/accounts] updated token. New expiration is 9/14/2020 3:22:06 PM
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Debug: [easyhttp] [5] POST to https://bits.roonlabs.net/1/q/roon.base.,roon.childprocess.,roon.debug.,roon.broker.,roon.dsp.,roon.sood.?roon_auth_token=72d2dc5b-267b-401d-b10f-fcdde5b6647b
08/15 15:22:06 Trace: [fiveaccountserver] GOT {“status”:“Success”,“profiles”:[{“id”:“f7a0941a-a1d4-46d1-a145-d7dc372cf606”,“name”:“Michael”,“birthdate”:“19530000”}]}
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/profileslist?token=72d2dc5b-267b-401d-b10f-fcdde5b6647b
08/15 15:22:06 Trace: [fiveaccountserver] GET https://accounts5.roonlabs.com/accounts/3/userinfo?token=72d2dc5b-267b-401d-b10f-fcdde5b6647b&machineid=0b63a16f-c27a-e464-950f-b55da0ea4612
08/15 15:22:06 Trace: [broker/accounts] updated token. New expiration is 9/14/2020 3:22:06 PM
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Info: [broker/locations] updating location Tidal:Name=TIDAL:Id=a2bc918b-81fb-abc3-f24b-19bcfed910a2
08/15 15:22:06 Info: [broker/locations] updating location Tidal:Name=TIDAL:Id=a2bc918b-81fb-abc3-f24b-19bcfed910a2
08/15 15:22:06 Info: [broker/locations] adding storage location: Qobuz:Name=QOBUZ:Id=f4845d8f-2574-4afe-9fbf-0a3ba8f37867
08/15 15:22:06 Info: [qobuz] [module] update qobuz location => False
08/15 15:22:06 Info: [broker/locations] updating location Qobuz:Name=QOBUZ:Id=f4845d8f-2574-4afe-9fbf-0a3ba8f37867
08/15 15:22:06 Trace: [fiveaccountserver] GOT {“status”:“Success”,“profiles”:[{“id”:“f7a0941a-a1d4-46d1-a145-d7dc372cf606”,“name”:“Michael”,“birthdate”:“19530000”}]}
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Debug: [easyhttp] [5] POST to https://bits.roonlabs.net/1/q/roon.base.,roon.childprocess.,roon.debug.,roon.broker.,roon.dsp.,roon.sood.?roon_auth_token=72d2dc5b-267b-401d-b10f-fcdde5b6647b returned after 331 ms, status code: 200
08/15 15:22:06 Trace: [fiveaccountserver] GOT {“user”:{“userid":“7cd10fb2-6cd6-498d-8c24-b5976675201c”,“firstname”:“Michael”,“lastname”:“Bade”,“email”:"michael.bade@calochortus.net”,“joinmailinglist”:true,“class”:“Normal”,“groups”:[],“trialallowed”:false},“status”:“Success”}
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:06 Trace: [bits] updated bits, in 348ms
08/15 15:22:06 Info: LOGIN RESULT {“user_id”:“1199394”,“user_auth_token”:“GNB79_gwEPG7b60mmKgQwrME7WUlVEUsP_wXmE-EXYx1gsoCe84WQ7OGAqoIVwPiaZfnPVcXF7_fetiqHH-12Q”,“user”:{“id":“1199394”,“publicId”:“qobuz:user:JX0W3nkawdrwy”,“email”:"mikebade@msn.com”,“login”:"mikebade@msn.com",“firstname”:null,“lastname”:null,“country_code”:“US”,“language_code”:“en”,“zone”:“US”,“store”:“US-en”,“country”:“US”,“avatar”:“https://www.gravatar.com/avatar/fb3dbc5137a4ff5713436773ea287c94?s=50&d=mm",“credential”:{“id”:“687968”,“label”:“streaming-studio”,“description”:"Subscriber Qobuz Studio”,“parameters”:{“label”:“Qobuz Studio”,“short_label”:“Studio”,“lossy_streaming”:true,“lossless_streaming”:true,“mobile_streaming”:true,“offline_streaming”:true,“hires_purchases_streaming”:true,“hfp_purchase”:false,“included_format_group_ids”:[1,2,3,4],“included_supplier_ids”:[],“included_label_ids”:[]}},“device”:{“id”:“3411019”,“device_manufacturer_id”:“f171268a43544f44a3299c8e238a4aaa”,“device_model”:null,“device_os_version”:null,“device_platform”:null}}}
08/15 15:22:06 Info: [broker/locations] updating location Qobuz:Name=QOBUZ:Id=f4845d8f-2574-4afe-9fbf-0a3ba8f37867
08/15 15:22:06 Info: [broker/locations] updating location Qobuz:Name=QOBUZ:Id=f4845d8f-2574-4afe-9fbf-0a3ba8f37867
08/15 15:22:06 Trace: [fiveaccountserver] GOT {“user”:{“userid":“7cd10fb2-6cd6-498d-8c24-b5976675201c”,“firstname”:“Michael”,“lastname”:“Bade”,“email”:"michael.bade@calochortus.net”,“joinmailinglist”:true,“class”:“Normal”,“groups”:[],“trialallowed”:false},“status”:“Success”}
08/15 15:22:06 Trace: [broker/accounts] Data updated. AccountStatus=LoggedIn MachineStatus=Licensed UserId=7cd10fb2-6cd6-498d-8c24-b5976675201c
08/15 15:22:15 Trace: Successful POST response from https://push.roonlabs.com/push/1/connect
08/15 15:22:15 Trace: [push] connecting to 34.75.249.100:9200
08/15 15:22:18 Trace: [push] connected
08/15 15:22:19 Info: [stats] 3061mb Virtual, 764mb Physical, 514mb Managed, 0 Handles, 35 Threads
08/15 15:22:21 Trace: [library] finished with 53504 dirty tracks 4076 dirty albums 34895 dirty performers 25637 dirty works 37923 dirty performances 1256 dirty genres 938 dirty auxfiles 147 dirty countries 9 dirty periods 43 dirty forms 3906 dirty places 1714 dirty creditroles 2292 dirty labels 0 clumping tracks, 0 clumping auxfiles 0 compute tracks, 0 deleted tracks, 53504 tracks to (re)load, 0 tracks to retain, 938 auxfiles to (re)load, 0 auxfiles to retain, and 157291 changed objects
08/15 15:22:21 Trace: [dbperf] flush 0 bytes, 0 ops in 10878 ms (cumulative 0 bytes, 0 ops in 10878 ms)

nuwriy · August 27, 2020, 3:28pm

Hello @Michael_Bade, and thanks for the report! Are you able to upload that diagnostic report here for me? I sent some information over to the team and they would like to take a look at the full logs if possible. Thanks! Please let me know when you have had a chance to upload that file so I can get it over to the team as quickly as possible.

Michael_Bade · August 27, 2020, 6:45pm

Yes, I uploaded. Thanks!
Mike

system · August 27, 2021, 6:45pm

This topic was automatically closed 365 days after the last reply. New replies are no longer allowed.