large HD issue

Help and Support Help
1

I’m using a Raspberry PI Allo Kali and Piano 2.1. Latest Volumio build.
This doesn’t make sense but I’ve repeated it over and over and it’s driving me bonkers. I’ve been collecting music for over 40 years and have 2TB of music on an external USB hard drive. Previously I was using a cheapo 5v 3amp powersupply with no issues. On a whim I tried running the setup using a battery Power Pack. The sound quality was greatly improved. The issue is that I’m unable to start and see the attached drive using the battery. If I disconnect the drive and use DLNA connection I can play the files but have to schlep the USB hard drive around… not a good solution.

I purchased an ALLO powersupply thinking that would help. Same issue as the battery.

Here’s the twist. If i unplug the drive and start Volumio it comes up. I can then connect the drive and let Volumio build the database and it works fine until I have to restart and then using either the battery or the Allo 3A powersupply it won’t come up. The USB drive is powered so that shouldn’t be a problem. If I reconnect the old cheapo power supply it will come up with no issues and displays all the files it just doesn’t sound as good. It takes quite a while index 2 TB of data so unplugging the drive and letting it rebuild the index every time isn’t really the solution. The battery pack lasts almost 8 hours and is awesome but doesn’t work ?

Now I’m even more confused. After unplugging and repluging in the hard drive It didn’t come up with el cheapo power supply as it had done multiple times befor.
'm currently rebuitlding the db with the elcheIapo power supply built in which usually takes overnight to complete.

So now it’s just a similar problem as otheres is large number of files don’t allow for a reboot. It will build but if you have to turn it off it no longer will come up and you have rebuild again.

What do I have to do to identify the real issue and how do I fix it?

Additional observation. If you restart Volumio with the hard drive attached the green line accross the top goes almost to the right edge and stops just short but the Music Library remains blank. If you shut down the USB NAS icons show up but it’s too late because you are shutting down.

2

Can you give some more specs. like which model Raspberry Pi and the model of external HDD you are using? Also, what do the logs show? Since the hdd has external power, the Pi will try to detect and mount it during startup, and this will pop up in the logs.

3

Rasberry PI 3

Seagate 3tb expansion drive

volumio@volumio:/var/log$ sudo journalctl -b
-- Logs begin at Thu 2018-11-15 02:07:31 UTC, end at Thu 2018-11-15 02:17:49 UTC
Nov 15 02:07:31 volumio systemd-journal[183]: Runtime journal is using 4.0M (max
Nov 15 02:07:31 volumio kernel: Booting Linux on physical CPU 0x0
Nov 15 02:07:31 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
Nov 15 02:07:31 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
Nov 15 02:07:31 volumio kernel: CPU: div instructions available: patching divisi
Nov 15 02:07:31 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
Nov 15 02:07:31 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
Nov 15 02:07:31 volumio kernel: Memory policy: Data cache writealloc
Nov 15 02:07:31 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 15 02:07:31 volumio kernel: On node 0 totalpages: 255488
Nov 15 02:07:31 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod
Nov 15 02:07:31 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 15 02:07:31 volumio kernel:   Normal zone: 0 pages reserved
Nov 15 02:07:31 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 15 02:07:31 volumio kernel: random: fast init done
Nov 15 02:07:31 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r
Nov 15 02:07:31 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*
Nov 15 02:07:31 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 15 02:07:31 volumio kernel: Built 1 zonelists, mobility grouping on.  Total
Nov 15 02:07:31 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.
Nov 15 02:07:31 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by
Nov 15 02:07:31 volumio kernel: Dentry cache hash table entries: 131072 (order:
lines 1-23...skipping...
-- Logs begin at Thu 2018-11-15 02:07:31 UTC, end at Thu 2018-11-15 02:17:49 UTC. --
Nov 15 02:07:31 volumio systemd-journal[183]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 73.2M free of 484.2M available <E2><86><92> current limit 30.0M).
Nov 15 02:07:31 volumio kernel: Booting Linux on physical CPU 0x0
Nov 15 02:07:31 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1101 SMP Thu Mar 22 17:27:30 GMT 2018
Nov 15 02:07:31 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Nov 15 02:07:31 volumio kernel: CPU: div instructions available: patching division code
Nov 15 02:07:31 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Nov 15 02:07:31 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
Nov 15 02:07:31 volumio kernel: Memory policy: Data cache writealloc
Nov 15 02:07:31 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 15 02:07:31 volumio kernel: On node 0 totalpages: 255488
Nov 15 02:07:31 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, node_mem_map bd336000
Nov 15 02:07:31 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 15 02:07:31 volumio kernel:   Normal zone: 0 pages reserved
Nov 15 02:07:31 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 15 02:07:31 volumio kernel: random: fast init done
Nov 15 02:07:31 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r8192 d22720 u69632
Nov 15 02:07:31 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
Nov 15 02:07:31 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 15 02:07:31 volumio kernel: Built 1 zonelists, mobility grouping on.  Total pages: 253242
Nov 15 02:07:31 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3f000000 vc_m
Nov 15 02:07:31 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 bytes)
Nov 15 02:07:31 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Nov 15 02:07:31 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Nov 15 02:07:31 volumio kernel: Memory: 976680K/1021952K available (7168K kernel code, 550K rwdata, 2048K rodata, 1024K init, 706K bss, 37080K reserved, 8192K cma-reserved)
Nov 15 02:07:31 volumio kernel: Virtual kernel memory layout:
                                    vector  : 0xffff0000 - 0xffff1000   (   4 kB)
                                    fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
                                    vmalloc : 0xbe800000 - 0xff800000   (1040 MB)
                                    lowmem  : 0x80000000 - 0xbe600000   ( 998 MB)
                                    modules : 0x7f000000 - 0x80000000   (  16 MB)
                                      .text : 0x80008000 - 0x80800000   (8160 kB)
                                      .init : 0x80b00000 - 0x80c00000   (1024 kB)
                                      .data : 0x80c00000 - 0x80c8986c   ( 551 kB)
                                       .bss : 0x80c90f40 - 0x80d417f4   ( 707 kB)
Nov 15 02:07:31 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Nov 15 02:07:31 volumio kernel: ftrace: allocating 24917 entries in 74 pages
Nov 15 02:07:31 volumio kernel: Hierarchical RCU implementation.
Nov 15 02:07:31 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Nov 15 02:07:31 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Nov 15 02:07:31 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Nov 15 02:07:31 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Nov 15 02:07:31 volumio kernel: Switching to timer-based delay loop, resolution 52ns
Nov 15 02:07:31 volumio kernel: Console: colour dummy device 80x30
Nov 15 02:07:31 volumio kernel: console [tty1] enabled
Nov 15 02:07:31 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Nov 15 02:07:31 volumio kernel: pid_max: default: 32768 minimum: 301
Nov 15 02:07:31 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Nov 15 02:07:31 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Nov 15 02:07:31 volumio kernel: Disabling memory control group subsystem
Nov 15 02:07:31 volumio kernel: CPU: Testing write buffer coherency: ok
Nov 15 02:07:31 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Nov 15 02:07:31 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c
Nov 15 02:07:31 volumio kernel: Hierarchical SRCU implementation.
Nov 15 02:07:31 volumio kernel: smp: Bringing up secondary CPUs ...
Nov 15 02:07:31 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Nov 15 02:07:31 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
volumio@volumio:/var/log$ volumio@volumio:/var/log$ sudo journalctl -b
-bash: volumio@volumio:/var/log$: No such file or directory
Nov 15 02:07:31 volumio kernel: Booting Linux on physical CPU 0x0
Nov 15 02:07:31 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
Nov 15 02:07:31 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
Nov 15 02:07:31 volumio kernel: CPU: div instructions available: patching divisi
Nov 15 02:07:31 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
Nov 15 02:07:31 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
Nov 15 02:07:31 volumio kernel: Memory policy: Data cache writealloc
Nov 15 02:07:31 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 15 02:07:31 volumio kernel: On node 0 totalpages: 255488
Nov 15 02:07:31 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod
Nov 15 02:07:31 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 15 02:07:31 volumio kernel:   Normal zone: 0 pages reserved
Nov 15 02:07:31 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 15 02:07:31 volumio kernel: random: fast init done
Nov 15 02:07:31 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r
Nov 15 02:07:31 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*
Nov 15 02:07:31 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 15 02:07:31 volumio kernel: Built 1 zonelists, mobility grouping on.  Total
Nov 15 02:07:31 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.
Nov 15 02:07:31 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by
Nov 15 02:07:31 volumio kernel: Dentry cache hash table entries: 131072 (order:
lines 1-23...skipping...
-- Logs begin at Thu 2018-11-15 02:07:31 UTC, end at Thu 2018-11-15 02:17:49 UTC. --
volumio@volumio:/var/log$ -- Logs begin at Thu 2018-11-15 02:07:31 UTC, end at Thu 2018-11-15 02:17:49 UTC
-bash: --: command not found
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio systemd-journal[183]: Runtime journal is using 4.0M (max
-bash: syntax error near unexpected token `('
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: Booting Linux on physical CPU 0x0
-bash: Nov: command not found
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
-bash: syntax error near unexpected token `('
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
-bash: syntax error near unexpected token `('
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: CPU: div instructions available: patching divisi
-bash: Nov: command not found
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
-bash: Nov: command not found
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
-bash: Nov: command not found
volumio@volumio:/var/log$ Nov 15 02:07:31 volumio kernel: Memory policy: Data cache writealloc
4

The usb-hdd is USB 3.0 already, right?

The snippet of the log is not very insightful yet, as it is only a small part of the logs without boot info.
Two questions:

  1. What is the output of the command ‘lsusb’?
  2. Can you run ‘sudo journalctl | grep -i usb’, preferably once when the HDD is recognised and again after a reboot when the HDD is not recognised anymore? Copy the output here (and please use the code-tags for it for readability. :wink: )
5

the hard drive is Compatible with USB 3.0 and USB 2.0 not sure that means it’s really USB 3.0.

I’m a real newbie at logs so I think you’re saying

After the reboot I plug in the USB drive and it builds the DB.
sudo journalctl | grep -i usb

After a couple of hours reboot and and provide those logs.
sudo journalctl | grep -i usb

Not sure what you mean by code tags???

After the reboot after sudo journalctl | grep -i usb is there another command that will assist? How long after reboot do I wait before capturing?

and oh yeah THANKS!

Nov 16 00:18:49 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:18:59 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:19:03 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:19:22 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:19:28 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:19:33 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:19:37 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:19:43 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:19:47 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:19:51 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:19:58 volumio sudo[9254]: volumio : TTY=pts/0 ; PWD=/var/log ; USER=ro
Nov 16 00:19:58 volumio sudo[9254]: pam_unix(sudo:session): session opened for u
Nov 16 00:20:02 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:20:10 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:20:14 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:20:20 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:20:47 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:20:50 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 00:20:54 volumio kernel: Voltage normalised (0x00000000)
Nov 16 00:20:56 volumio kernel: Under-voltage detected! (0x00050005)
Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
volumio@volumio:~$ lsusb
Bus 001 Device 004: ID 05dc:a81d Lexar Media, Inc.
Bus 001 Device 006: ID 0bc2:3322 Seagate RSS LLC
Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter
Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp.
Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub

USB pluged in last night after failed reboot but built the DB

volumio@volumio:~$ sudo journalctl | grep -i usb
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver usbfs
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver hub
Nov 15 02:07:31 volumio kernel: usbcore: registered new device driver usb
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver lan78xx
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver smsc95xx
Nov 15 02:07:31 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller
Nov 15 02:07:31 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Nov 15 02:07:31 volumio kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Nov 15 02:07:31 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Nov 15 02:07:31 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Nov 15 02:07:31 volumio kernel: usb usb1: Product: DWC OTG Controller
Nov 15 02:07:31 volumio kernel: usb usb1: Manufacturer: Linux 4.14.29-v7+ dwc_otg_hcd
Nov 15 02:07:31 volumio kernel: usb usb1: SerialNumber: 3f980000.usb
Nov 15 02:07:31 volumio kernel: hub 1-0:1.0: USB hub found
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver usb-storage
Nov 15 02:07:31 volumio kernel: usbcore: registered new interface driver usbhid
Nov 15 02:07:31 volumio kernel: usbhid: USB HID core driver
Nov 15 02:07:31 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Nov 15 02:07:31 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Nov 15 02:07:31 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Nov 15 02:07:31 volumio kernel: hub 1-1:1.0: USB hub found
Nov 15 02:07:31 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Nov 15 02:07:31 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Nov 15 02:07:31 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Nov 15 02:07:31 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:21:43:9f
Nov 15 02:07:31 volumio kernel: usb 1-1.5: new high-speed USB device number 4 using dwc_otg
Nov 15 02:07:31 volumio kernel: usb 1-1.5: New USB device found, idVendor=05dc, idProduct=a81d
Nov 15 02:07:31 volumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Nov 15 02:07:31 volumio kernel: usb 1-1.5: Product: USB Flash Drive
Nov 15 02:07:31 volumio kernel: usb 1-1.5: Manufacturer: Lexar
Nov 15 02:07:31 volumio kernel: usb 1-1.5: SerialNumber: AANSM0EYVDW6N3QX
Nov 15 02:07:31 volumio kernel: usb-storage 1-1.5:1.0: USB Mass Storage device detected
Nov 15 02:07:31 volumio kernel: scsi host0: usb-storage 1-1.5:1.0
Nov 15 02:07:31 volumio kernel: usb 1-1.2: new high-speed USB device number 5 using dwc_otg
Nov 15 02:07:31 volumio kernel: usb 1-1.2: New USB device found, idVendor=0bc2, idProduct=3322
Nov 15 02:07:31 volumio kernel: usb 1-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Nov 15 02:07:31 volumio kernel: usb 1-1.2: Product: Expansion Desk
Nov 15 02:07:31 volumio kernel: usb 1-1.2: Manufacturer: Seagate
Nov 15 02:07:31 volumio kernel: usb 1-1.2: SerialNumber: NA8FHGJJ
Nov 15 02:07:31 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected
Nov 15 02:07:31 volumio kernel: scsi host1: usb-storage 1-1.2:1.0
Nov 15 02:07:31 volumio kernel: scsi 0:0:0:0: Direct-Access     Lexar    USB Flash Drive  1100 PQ: 0 ANSI: 4
Nov 15 02:07:31 volumio initramfs: Checking for USB updates
Nov 15 02:07:32 volumio kernel: usbcore: registered new interface driver brcmfmac
Nov 15 02:32:53 volumio kernel: usb 1-1.2: USB disconnect, device number 5
Nov 15 02:33:48 volumio volumio[924]: info: CURURI: music-library/USB
Nov 15 02:33:51 volumio volumio[924]: info: CURURI: music-library/USB/Lexar
Nov 15 02:34:07 volumio volumio[924]: info: CURURI: music-library/USB
Nov 15 02:34:45 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using dwc_otg
Nov 15 02:34:45 volumio kernel: usb 1-1.2: New USB device found, idVendor=0bc2, idProduct=3322
Nov 15 02:34:45 volumio kernel: usb 1-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Nov 15 02:34:45 volumio kernel: usb 1-1.2: Product: Expansion Desk
Nov 15 02:34:45 volumio kernel: usb 1-1.2: Manufacturer: Seagate
Nov 15 02:34:45 volumio kernel: usb 1-1.2: SerialNumber: NA8FHGJJ
Nov 15 02:34:45 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected
Nov 15 02:34:45 volumio kernel: scsi host1: usb-storage 1-1.2:1.0
Nov 15 02:35:28 volumio volumio[924]: info: CURURI: music-library/USB
Nov 15 02:35:30 volumio volumio[924]: info: CURURI: music-library/USB/Seagate Expansion Drive
Nov 15 02:49:40 volumio volumio[924]: info: CURURI: music-library/USB
Nov 15 02:49:42 volumio volumio[924]: info: CURURI: music-library/USB/Seagate Expansion Drive
Nov 15 02:49:44 volumio volumio[924]: info: CURURI: music-library/USB/Seagate Expansion Drive/Lossless
Nov 15 13:28:08 volumio volumio[924]: info: CURURI: music-library/USB
Nov 15 13:28:10 volumio volumio[924]: info: CURURI: music-library/USB/Seagate Expansion Drive
volumio@volumio:~$
6 

[/code]Aftere reboot Hard drive and usb drive don't show up


[code]volumio@volumio:~$ sudo journalctl | grep -i usb
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver usbfs
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver hub
Nov 16 03:17:29 volumio kernel: usbcore: registered new device driver usb
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver lan78xx
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver smsc95xx
Nov 16 03:17:29 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller
Nov 16 03:17:29 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Nov 16 03:17:29 volumio kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Nov 16 03:17:29 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Nov 16 03:17:29 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Nov 16 03:17:29 volumio kernel: usb usb1: Product: DWC OTG Controller
Nov 16 03:17:29 volumio kernel: usb usb1: Manufacturer: Linux 4.14.29-v7+ dwc_otg_hcd
Nov 16 03:17:29 volumio kernel: usb usb1: SerialNumber: 3f980000.usb
Nov 16 03:17:29 volumio kernel: hub 1-0:1.0: USB hub found
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver usb-storage
Nov 16 03:17:29 volumio kernel: usbcore: registered new interface driver usbhid
Nov 16 03:17:29 volumio kernel: usbhid: USB HID core driver
Nov 16 03:17:29 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Nov 16 03:17:29 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514
Nov 16 03:17:29 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Nov 16 03:17:29 volumio kernel: hub 1-1:1.0: USB hub found
Nov 16 03:17:29 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Nov 16 03:17:29 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
Nov 16 03:17:29 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Nov 16 03:17:29 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:21:43:9f
Nov 16 03:17:29 volumio kernel: usb 1-1.5: new high-speed USB device number 4 using dwc_otg
Nov 16 03:17:29 volumio kernel: usb 1-1.5: New USB device found, idVendor=05dc, idProduct=a81d
Nov 16 03:17:29 volumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Nov 16 03:17:29 volumio kernel: usb 1-1.5: Product: USB Flash Drive
Nov 16 03:17:29 volumio kernel: usb 1-1.5: Manufacturer: Lexar
Nov 16 03:17:29 volumio kernel: usb 1-1.5: SerialNumber: AANSM0EYVDW6N3QX
Nov 16 03:17:29 volumio kernel: usb-storage 1-1.5:1.0: USB Mass Storage device detected
Nov 16 03:17:29 volumio kernel: scsi host0: usb-storage 1-1.5:1.0
Nov 16 03:17:29 volumio kernel: usb 1-1.2: new high-speed USB device number 5 using dwc_otg
Nov 16 03:17:29 volumio kernel: usb 1-1.2: New USB device found, idVendor=0bc2, idProduct=3322
Nov 16 03:17:29 volumio kernel: usb 1-1.2: New USB device strings: Mfr=2, Product=3, SerialNumber=1
Nov 16 03:17:29 volumio kernel: usb 1-1.2: Product: Expansion Desk
Nov 16 03:17:29 volumio kernel: usb 1-1.2: Manufacturer: Seagate
Nov 16 03:17:29 volumio kernel: usb 1-1.2: SerialNumber: NA8FHGJJ
Nov 16 03:17:29 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected
Nov 16 03:17:29 volumio kernel: scsi host1: usb-storage 1-1.2:1.0
Nov 16 03:17:29 volumio kernel: scsi 0:0:0:0: Direct-Access     Lexar    USB Flash Drive  1100 PQ: 0 ANSI: 4
Nov 16 03:17:29 volumio initramfs: Checking for USB updates
Nov 16 03:17:30 volumio kernel: usbcore: registered new interface driver brcmfmac
volumio@volumio:~$[/code]

[code]
volumio@192.168.0.13's password:
                       ___
                      /\_ \                        __
         __  __    ___\//\ \    __  __    ___ ___ /\_\    ___
        /\ \/\ \  / __`\\ \ \  /\ \/\ \ /' __` __`\/\ \  / __`\
        \ \ \_/ |/\ \L\ \\_\ \_\ \ \_\ \/\ \/\ \/\ \ \ \/\ \L\ \
         \ \___/ \ \____//\____\\ \____/\ \_\ \_\ \_\ \_\ \____/
          \/__/   \/___/ \/____/ \/___/  \/_/\/_/\/_/\/_/\/___/

             Free Audiophile Linux Music Player - Version 2.0

          C 2015 Michelangelo Guarise - Volumio Team - Volumio.org


Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
volumio@volumio:~$ sudo journalctl -b
-- Logs begin at Fri 2018-11-16 03:17:29 UTC, end at Fri 2018-11-16 03:41:04 UTC
Nov 16 03:17:29 volumio systemd-journal[184]: Runtime journal is using 4.0M (max
Nov 16 03:17:29 volumio kernel: Booting Linux on physical CPU 0x0
Nov 16 03:17:29 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
Nov 16 03:17:29 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
Nov 16 03:17:29 volumio kernel: CPU: div instructions available: patching divisi
Nov 16 03:17:29 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
Nov 16 03:17:29 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
Nov 16 03:17:29 volumio kernel: Memory policy: Data cache writealloc
Nov 16 03:17:29 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 16 03:17:29 volumio kernel: On node 0 totalpages: 255488
Nov 16 03:17:29 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod
Nov 16 03:17:29 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 16 03:17:29 volumio kernel:   Normal zone: 0 pages reserved
Nov 16 03:17:29 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 16 03:17:29 volumio kernel: random: fast init done
Nov 16 03:17:29 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r
Nov 16 03:17:29 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*
Nov 16 03:17:29 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 16 03:17:29 volumio kernel: Built 1 zonelists, mobility grouping on.  Total
Nov 16 03:17:29 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.
Nov 16 03:17:29 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by
Nov 16 03:17:29 volumio kernel: Dentry cache hash table entries: 131072 (order:
volumio@volumio:~$ ^C
volumio@volumio:~$ ^C
volumio@volumio:~$ ^C
volumio@volumio:~$ sudo journalctl -b
-- Logs begin at Fri 2018-11-16 03:17:29 UTC, end at Fri 2018-11-16 03:43:07 UTC
Nov 16 03:17:29 volumio systemd-journal[184]: Runtime journal is using 4.0M (max
Nov 16 03:17:29 volumio kernel: Booting Linux on physical CPU 0x0
Nov 16 03:17:29 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
Nov 16 03:17:29 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
Nov 16 03:17:29 volumio kernel: CPU: div instructions available: patching divisi
Nov 16 03:17:29 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
Nov 16 03:17:29 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
Nov 16 03:17:29 volumio kernel: Memory policy: Data cache writealloc
Nov 16 03:17:29 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 16 03:17:29 volumio kernel: On node 0 totalpages: 255488
Nov 16 03:17:29 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod
Nov 16 03:17:29 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 16 03:17:29 volumio kernel:   Normal zone: 0 pages reserved
Nov 16 03:17:29 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 16 03:17:29 volumio kernel: random: fast init done
Nov 16 03:17:29 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r
Nov 16 03:17:29 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*
Nov 16 03:17:29 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 16 03:17:29 volumio kernel: Built 1 zonelists, mobility grouping on.  Total
Nov 16 03:17:29 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.
Nov 16 03:17:29 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by
Nov 16 03:17:29 volumio kernel: Dentry cache hash table entries: 131072 (order:
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Fri 2018-11-16 03:17:29 UTC. --
Nov 16 03:43:29 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 03:43:30 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 16 03:43:35 volumio kernel: Voltage normalised (0x00000000)
Nov 16 03:43:47 volumio volumio[925]: Upnp client error: Error: This socket has been ended by the other party
Nov 16 03:43:47 volumio volumio[925]: error: MPD error: Error: read ECONNRESET
Nov 16 03:43:47 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 16 03:43:47 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 16 03:43:47 volumio systemd[1]: Starting Music Player Daemon...
Nov 16 03:44:08 volumio sudo[1438]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 16 03:44:08 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 16 03:44:23 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 03:44:30 volumio kernel: Voltage normalised (0x00000000)
volumio@volumio:~$ sudo journalctl -b
-- Logs begin at Fri 2018-11-16 03:17:29 UTC, end at Fri 2018-11-16 03:43:07 UTC
Nov 16 03:17:29 volumio systemd-journal[184]: Runtime journal is using 4.0M (max
Nov 16 03:17:29 volumio kernel: Booting Linux on physical CPU 0x0
Nov 16 03:17:29 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (
Nov 16 03:17:29 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv
Nov 16 03:17:29 volumio kernel: CPU: div instructions available: patching divisi
Nov 16 03:17:29 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al
Nov 16 03:17:29 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R
Nov 16 03:17:29 volumio kernel: Memory policy: Data cache writealloc
Nov 16 03:17:29 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000
Nov 16 03:17:29 volumio kernel: On node 0 totalpages: 255488
Nov 16 03:17:29 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod
Nov 16 03:17:29 volumio kernel:   Normal zone: 2246 pages used for memmap
Nov 16 03:17:29 volumio kernel:   Normal zone: 0 pages reserved
Nov 16 03:17:29 volumio kernel:   Normal zone: 255488 pages, LIFO batch:31
Nov 16 03:17:29 volumio kernel: random: fast init done
Nov 16 03:17:29 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r
Nov 16 03:17:29 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*
Nov 16 03:17:29 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Nov 16 03:17:29 volumio kernel: Built 1 zonelists, mobility grouping on.  Total
Nov 16 03:17:29 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.
Nov 16 03:17:29 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by
Nov 16 03:17:29 volumio kernel: Dentry cache hash table entries: 131072 (order:
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Fri 2018-11-16 03:17:29 UTC. --
Nov 16 03:43:29 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 03:43:30 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 16 03:43:35 volumio kernel: Voltage normalised (0x00000000)
Nov 16 03:43:47 volumio volumio[925]: Upnp client error: Error: This socket has been ended by the other party
Nov 16 03:43:47 volumio volumio[925]: error: MPD error: Error: read ECONNRESET
Nov 16 03:43:47 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 16 03:43:47 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 16 03:43:47 volumio systemd[1]: Starting Music Player Daemon...
Nov 16 03:44:08 volumio sudo[1438]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 16 03:44:08 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 16 03:44:23 volumio kernel: Under-voltage detected! (0x00050005)
Nov 16 03:44:30 volumio kernel: Voltage normalised (0x00000000)
[/code]

Switced back to the old powersupply with the hard drive attached.
[code]volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Fri 2018-11-16 03:17:29 UTC. --
Nov 16 04:13:33 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 16 04:13:33 volumio systemd[1]: Starting Music Player Daemon...
Nov 16 04:15:03 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 16 04:15:09 volumio volumio[911]: Upnp client error: Error: This socket has been ended by the other party
Nov 16 04:15:09 volumio volumio[911]: error: MPD error: Error: read ECONNRESET
Nov 16 04:15:10 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 16 04:15:10 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 16 04:15:10 volumio systemd[1]: Starting Music Player Daemon...
Nov 16 04:15:59 volumio sudo[1379]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 16 04:15:59 volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
7

Thanks Danager, that is very helpful.

Few things that I see:

  • Your first log shows a lot of undervoltage warnings. I know these appear in the logs more often in Volumio due to certain CPU settings, but here it happens quite often; a bit too often I would say. This however does not appear to be the main problem.
  • The Seagate hard drive is detected on every boot (which you can see in the USB logs)
  • The MPD service keeps restarting. This might cause your database issues.

If I read your story correctly, you “hotplug” the drive (that is, you plug it in when the R-Pi is already running). Why don’t you just leave the drive connected permanently? Once it is added, Volumio should auto-mount it upon boot. The database is non-volatile, it stays after shutdown or reboot. I can imagine that if you boot the device without the drive, MPD might try to update from an unmounted drive. Halfway through you plug the drive and it just goes haywire.

Just a few tips on the logging, to help you focus the search a bit more. My advice would be to reboot the device a few times, verify that the drive is detected (with “lsusb”) and check what is going on with MPD.

  • “Journalctl -f” will show the most recent entries
  • “Journalctl -b” shows the entire logs since the last boot
  • Adding " | grep -i usb" selects only USB-related messages
  • With “lsusb” you can list the connected USB devices if successfully recognised.
8

Paul first thanks again for the help. I can’t leave the drive plugged in … that’s the issue. On reboot with the Seagate drive attached since the new volumio version it never appears in the music library. I’ve waited over 12 hours and the music library remains blank. During that time I’m also unable to connect to it using Upnp so only Spotify works.

If I unplug the Seagate drive and only boot with or without a USB flash drive Volumio works fine but I don’t have access to the music library until I plug it in and wait while index is rebuilt. This takes quite a few hours but it works fine until I have to reboot again. This is the same behavior I had when I wanted to use a 2.4A power source on the previous version but if I used the 3A power source it worked. Now with the latest version both 3A power supplies fail update the music library on reboot.

Weird artifact. New reboot with Seagate USB drive attached the Music library is blank. Once I pick shutdown from the top right menu and select power off or restart the drives show in the music library for the brief time it’s shutting down. I would think that the drives are actually initializing but the software is failing on startup to recognize the large DB.

9

reboot with Seagate 3tb drive attached
Nov 17 05:56:18 volumio systemd[1]: mpd.service start operation timed out. Terminating. The Music library remains empty

volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Sat 2018-11-17 05:54:18 UTC. --
Nov 17 05:56:15 volumio systemd[1060]: Reached target Default.
Nov 17 05:56:15 volumio systemd[1060]: Startup finished in 43ms.
Nov 17 05:56:15 volumio systemd[1]: Started User Manager for UID 1000.
Nov 17 05:56:15 volumio sshd[1055]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Nov 17 05:56:15 volumio sshd[1055]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Nov 17 05:56:17 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 05:56:18 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 17 05:56:21 volumio kernel: Voltage normalised (0x00000000)
Nov 17 05:56:27 volumio sudo[1073]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 17 05:56:27 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 17 05:57:03 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 05:57:07 volumio kernel: Voltage normalised (0x00000000)

powered down and disconnected USB Seagate hard drive
Recognized the USB flash drive and Music library show in the content window

[code]volumio@volumio:~$ sudo journalctl -f
– Logs begin at Sat 2018-11-17 06:01:58 UTC. –
Nov 17 06:04:09 volumio systemd[1379]: Starting Basic System.
Nov 17 06:04:09 volumio systemd[1379]: Reached target Basic System.
Nov 17 06:04:09 volumio systemd[1379]: Starting Default.
Nov 17 06:04:09 volumio systemd[1379]: Reached target Default.
Nov 17 06:04:09 volumio systemd[1379]: Startup finished in 66ms.
Nov 17 06:04:09 volumio systemd[1]: Started User Manager for UID 1000.
Nov 17 06:04:09 volumio sshd[1310]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Nov 17 06:04:09 volumio sshd[1310]: lastlog_openseek: Couldn’t stat /var/log/lastlog: No such file or directory
Nov 17 06:04:16 volumio sudo[1434]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Nov 17 06:04:16 volumio sudo[1434]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 17 06:04:24 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:04:31 volumio kernel: Under-voltage detected! (0x00050005)

Nov 17 06:04:54 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:04:56 volumio kernel: Under-voltage detected! (0x00050005)[/code]

Reboot successful with just USB flash drive no hard drive.

[code]volumio@volumio:~$ sudo journalctl -f
– Logs begin at Sat 2018-11-17 06:10:10 UTC. –
Nov 17 06:12:18 volumio systemd[1573]: Startup finished in 64ms.
Nov 17 06:12:18 volumio systemd[1]: Started User Manager for UID 1000.
Nov 17 06:12:18 volumio sshd[1456]: lastlog_openseek: Couldn’t stat /var/log/las tlog: No such file or directory
Nov 17 06:12:18 volumio sshd[1456]: lastlog_openseek: Couldn’t stat /var/log/las tlog: No such file or directory
Nov 17 06:12:25 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:12:31 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 06:12:35 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:12:37 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 06:12:48 volumio sudo[1789]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/bin/journalctl -f
Nov 17 06:12:48 volumio sudo[1789]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0)

Nov 17 06:13:28 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Nov 17 06:13:28 volumio volumio[897]: info: CURURI: music-library
Nov 17 06:13:29 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:13:32 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 06:13:33 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Nov 17 06:13:33 volumio volumio[897]: info: CURURI: music-library/USB
Nov 17 06:13:34 volumio volumio[897]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Nov 17 06:13:34 volumio volumio[897]: info: CURURI: music-library/USB/Lexar
Nov 17 06:13:46 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:14:03 volumio kernel: Under-voltage detected! (0x00050005)
Nov 17 06:14:07 volumio kernel: Voltage normalised (0x00000000)
Nov 17 06:14:11 volumio kernel: Under-voltage detected! (0x00050005)[/code]

After two successful reboots I powered down and unplugged the power. Plugged in the Seagate USB Drive and powered it back up.
It came up recognized the drive and started indexing. After it completes indexing (overnight) I will try a reboot with the drive attached.

volumio@volumio:~$ sudo journalctl -f -- Logs begin at Sat 2018-11-17 06:17:47 UTC. -- Nov 17 06:21:26 volumio systemd[1803]: Starting Basic System. Nov 17 06:21:26 volumio systemd[1803]: Reached target Basic System. Nov 17 06:21:26 volumio systemd[1803]: Starting Default. Nov 17 06:21:26 volumio systemd[1803]: Reached target Default. Nov 17 06:21:26 volumio systemd[1803]: Startup finished in 77ms. Nov 17 06:21:26 volumio systemd[1]: Started User Manager for UID 1000. Nov 17 06:21:26 volumio sshd[1656]: lastlog_openseek: Couldn't stat /var/log/las tlog: No such file or directory Nov 17 06:21:26 volumio sshd[1656]: lastlog_openseek: Couldn't stat /var/log/las tlog: No such file or directory Nov 17 06:21:33 volumio sudo[1865]: volumio : TTY=pts/0 ; PWD=/home/volumio ; US ER=root ; COMMAND=/bin/journalctl -f Nov 17 06:21:33 volumio sudo[1865]: pam_unix(sudo:session): session opened for u ser root by volumio(uid=0) Nov 17 06:21:34 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:21:36 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:21:44 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:21:46 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:22:26 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:22:30 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:22:42 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:22:45 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:22:53 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:22:55 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:23:01 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:23:03 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:24:13 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 17 06:24:13 volumio volumio[908]: info: CURURI: music-library/USB Nov 17 06:24:16 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 17 06:24:16 volumio volumio[908]: info: CURURI: music-library/USB/Seagate Expansion Drive Nov 17 06:24:24 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 17 06:24:24 volumio volumio[908]: info: CURURI: music-library/USB/Seagate Expansion Drive/Lossless Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 17 06:24:43 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 17 06:24:44 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:24:44 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 17 06:24:48 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:24:52 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:24:56 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:24:58 volumio kernel: Voltage normalised (0x00000000) Nov 17 06:25:00 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:02 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 06:25:04 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:08 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:12 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:16 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:20 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:24 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 17 06:25:28 volumio volumio[908]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats

While it’s building the index all the button titles are the original names common.tab_browse, common.tab_playback,

10

after two successful reboots with just a USB flash drive
powered off and attached Seagate USB drive
It built the Index.
Worked!
Rebooted waited six hours
Music LIbrary is empty
Cannot connect using UpNp
Spotify from PC connects to Volumio and will play music

volumio@volumio:~$ lsusb Bus 001 Device 004: ID 05dc:a81d Lexar Media, Inc. Bus 001 Device 005: ID 0bc2:3322 Seagate RSS LLC Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub volumio@volumio:~$ sudo journalctl -b -- Logs begin at Sat 2018-11-17 08:37:27 UTC, end at Sat 2018-11-17 14:21:54 UTC Nov 17 08:37:27 volumio systemd-journal[183]: Runtime journal is using 4.0M (max Nov 17 08:37:27 volumio kernel: Booting Linux on physical CPU 0x0 Nov 17 08:37:27 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) ( Nov 17 08:37:27 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv Nov 17 08:37:27 volumio kernel: CPU: div instructions available: patching divisi Nov 17 08:37:27 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al Nov 17 08:37:27 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R Nov 17 08:37:27 volumio kernel: Memory policy: Data cache writealloc Nov 17 08:37:27 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000 Nov 17 08:37:27 volumio kernel: On node 0 totalpages: 255488 Nov 17 08:37:27 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod Nov 17 08:37:27 volumio kernel: Normal zone: 2246 pages used for memmap Nov 17 08:37:27 volumio kernel: Normal zone: 0 pages reserved Nov 17 08:37:27 volumio kernel: Normal zone: 255488 pages, LIFO batch:31 Nov 17 08:37:27 volumio kernel: random: fast init done Nov 17 08:37:27 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r Nov 17 08:37:27 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17* Nov 17 08:37:27 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 17 08:37:27 volumio kernel: Built 1 zonelists, mobility grouping on. Total Nov 17 08:37:27 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb. Nov 17 08:37:27 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by Nov 17 08:37:27 volumio kernel: Dentry cache hash table entries: 131072 (order: lines 1-23

Nov 17 14:24:24 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 17 14:24:35 volumio volumio[929]: error: MPD error: Error: read ECONNRESET
Nov 17 14:24:36 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 17 14:24:36 volumio systemd[1]: Unit mpd.service entered failed state…
wish i new what this meant???
“ECONNRESET” means the other side of the TCP conversation abruptly closed its end of the connection. This is most probably due to one or more application protocol errors. You could look at the API server logs to see if it complains about something.

What could also be the case: at random times, the other side is “ECONNRESET” means the other side of the TCP conversation abruptly closed its end of the connection. This is most probably due to one or more application protocol errors. You could look at the API server logs to see if it complains about something.

What could also be the case: at random times, the other side is overloaded and simply kills the connection as a result. If that’s the case, depends on what you’re connecting to exactly

volumio@volumio:~$ sudo journalctl -f -- Logs begin at Sat 2018-11-17 08:37:27 UTC. -- Nov 17 14:22:53 volumio volumio[929]: Upnp client error: Error: This socket has been ended by the other party Nov 17 14:22:53 volumio systemd[1]: Failed to start Music Player Daemon. Nov 17 14:22:53 volumio systemd[1]: Unit mpd.service entered failed state. Nov 17 14:22:53 volumio systemd[1]: Starting Music Player Daemon... Nov 17 14:23:13 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 14:23:19 volumio kernel: Voltage normalised (0x00000000) Nov 17 14:23:29 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 14:23:33 volumio kernel: Voltage normalised (0x00000000) Nov 17 14:23:40 volumio sudo[4046]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Nov 17 14:23:40 volumio sudo[4046]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 17 14:24:09 volumio kernel: Under-voltage detected! (0x00050005) Nov 17 14:24:13 volumio kernel: Voltage normalised (0x00000000) Nov 17 14:24:24 volumio systemd[1]: mpd.service start operation timed out. Terminating. Nov 17 14:24:35 volumio volumio[929]: error: MPD error: Error: read ECONNRESET Nov 17 14:24:36 volumio systemd[1]: Failed to start Music Player Daemon. Nov 17 14:24:36 volumio systemd[1]: Unit mpd.service entered failed state. Nov 17 14:24:36 volumio systemd[1]: Starting Music Player Daemon...

rebooted again just to make sure

-- Logs begin at Sat 2018-11-17 14:36:11 UTC, end at Sat 2018-11-17 14:40:57 UTC. -- Nov 17 14:36:11 volumio systemd-journal[183]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 73.2M free of 484.2M available <E2><86><92> current limit 30.0M). Nov 17 14:36:11 volumio kernel: Booting Linux on physical CPU 0x0 Nov 17 14:36:11 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1101 SMP Thu Mar 22 17:27:30 GMT 2018 Nov 17 14:36:11 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Nov 17 14:36:11 volumio kernel: CPU: div instructions available: patching division code Nov 17 14:36:11 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Nov 17 14:36:11 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Nov 17 14:36:11 volumio kernel: Memory policy: Data cache writealloc Nov 17 14:36:11 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000 Nov 17 14:36:11 volumio kernel: On node 0 totalpages: 255488 Nov 17 14:36:11 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, node_mem_map bd336000 Nov 17 14:36:11 volumio kernel: Normal zone: 2246 pages used for memmap Nov 17 14:36:11 volumio kernel: Normal zone: 0 pages reserved Nov 17 14:36:11 volumio kernel: Normal zone: 255488 pages, LIFO batch:31 Nov 17 14:36:11 volumio kernel: random: fast init done Nov 17 14:36:11 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r8192 d22720 u69632 Nov 17 14:36:11 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096 Nov 17 14:36:11 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 17 14:36:11 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 253242 Nov 17 14:36:11 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3f000000 vc_m Nov 17 14:36:11 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 bytes) Nov 17 14:36:11 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Nov 17 14:36:11 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Nov 17 14:36:11 volumio kernel: Memory: 976680K/1021952K available (7168K kernel code, 550K rwdata, 2048K rodata, 1024K init, 706K bss, 37080K reserved, 8192K cma-reserved) Nov 17 14:36:11 volumio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe600000 ( 998 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x80800000 (8160 kB) .init : 0x80b00000 - 0x80c00000 (1024 kB) .data : 0x80c00000 - 0x80c8986c ( 551 kB) .bss : 0x80c90f40 - 0x80d417f4 ( 707 kB) Nov 17 14:36:11 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 17 14:36:11 volumio kernel: ftrace: allocating 24917 entries in 74 pages Nov 17 14:36:11 volumio kernel: Hierarchical RCU implementation. Nov 17 14:36:11 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 17 14:36:11 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Nov 17 14:36:11 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Nov 17 14:36:11 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Nov 17 14:36:11 volumio kernel: Switching to timer-based delay loop, resolution 52ns Nov 17 14:36:11 volumio kernel: Console: colour dummy device 80x30 Nov 17 14:36:11 volumio kernel: console [tty1] enabled Nov 17 14:36:11 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Nov 17 14:36:11 volumio kernel: pid_max: default: 32768 minimum: 301 Nov 17 14:36:11 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 17 14:36:11 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 17 14:36:11 volumio kernel: Disabling memory control group subsystem Nov 17 14:36:11 volumio kernel: CPU: Testing write buffer coherency: ok Nov 17 14:36:11 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Nov 17 14:36:11 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c Nov 17 14:36:11 volumio kernel: Hierarchical SRCU implementation. Nov 17 14:36:11 volumio kernel: smp: Bringing up secondary CPUs ... Nov 17 14:36:11 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Nov 17 14:36:11 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002

11

Good with the bad. I’m no longer able to restart my PI but I can transfer files over twice as fast as I could on previous version. My transfer rate before was 4mb per sec and not it’s over 10mb per sec!

Rebooting is overrated

12

You could live without reboots, but inevitably it happens and I know how frustrating it is when things don’t work anymore then.

I think we’ve hit an important point here. Volumio should mount all connected drives upon boot. If it does not do this, then something is wrong here already which likely propagates. I’m not sure how good Volumio is with hot-plugging. Question is, why is the drive not recognised on boot?
As for the other remark: it is not strange that the interface is less responsive when scanning a 3 Tb drive, as the scanning is CPU-heavy. This warning also pops up when the scanning is started.

The MPD error is still strange. It almost seems that something is faulty in the install.

Let’s start from the beginning, the hard drive. Can you try the following?

  1. Run the Seagate diagnostics tool on the drive from another workstation, to confirm it is working correctly.
  2. Install a fresh Volumio. Boot it without any drives attached and leave it for 10-20 minutes before setting up networking etc. Set up your networking and other settings and shut down. As described in the manual, Volumio needs a good 10 minutes or so on first boot to organise the entire install.
  3. Connect the Seagate drive (no other drives), power the Pi and when booted, run ‘lsusb’ and ‘sudo journalctl | grep -i usb’ again. If all is well, the drive is detected. If not, the logs might be able to show us what is going on.
13

my advice: don’t filter your journalctl with grep, give us the full log to make sure we don’t miss anything else…

14

Thanks everyone. I’m going out of town this week for the Thanksgiving so I won’t be able to try the suggestions until I get back. This SD card has gone through several updates and so starting fresh could help as I haven’t had this problem before with the 3A power supply. The Hot Plugging didn’t matter as I disconnected the drive restarted and Volumio came right up. Powered off and on without the drive no issues. Powered down connected and it mounted on the restart but had to re-index. After the index powering down or restarting didn’t work.

As a person who doesn’t know linux I can only suggest that it isn’t a USB issue per se as it will connect and build the index on a restart if the db as been reset (reboot with drive attached).

Happy Thanksgiving everyone even if you aren’t in the USA. It’s a time to reflect and realize how lucky we are. So again THANKS for the help.

15

Couldn’t get new version to write to the sd card???

I had an older version 2.389 that I wrote to a brand new sd card booted no drives.
Set it up
shut down
plugged in Seagate HD

Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent permitted by applicable law. volumio@volumio:~$ lsusb Bus 001 Device 004: ID 0bc2:3322 Seagate RSS LLC Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub volumio@volumio:~$ sudo journalctl -- Logs begin at Tue 2018-11-27 21:02:45 UTC, end at Tue 2018-11-27 21:09:58 UTC. -- Nov 27 21:02:45 volumio systemd-journal[170]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 73.2M free of 484.2M available <E2><86><92> current limit 30.0M). Nov 27 21:02:45 volumio kernel: Booting Linux on physical CPU 0x0 Nov 27 21:02:45 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1101 SMP Thu Mar 22 17:27:30 GMT 2018 Nov 27 21:02:45 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Nov 27 21:02:45 volumio kernel: CPU: div instructions available: patching division code Nov 27 21:02:45 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Nov 27 21:02:45 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Nov 27 21:02:45 volumio kernel: Memory policy: Data cache writealloc Nov 27 21:02:45 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000 Nov 27 21:02:45 volumio kernel: On node 0 totalpages: 255488 Nov 27 21:02:45 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, node_mem_map bd336000 Nov 27 21:02:45 volumio kernel: Normal zone: 2246 pages used for memmap Nov 27 21:02:45 volumio kernel: Normal zone: 0 pages reserved Nov 27 21:02:45 volumio kernel: Normal zone: 255488 pages, LIFO batch:31 Nov 27 21:02:45 volumio kernel: random: fast init done Nov 27 21:02:45 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r8192 d22720 u69632 Nov 27 21:02:45 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096 Nov 27 21:02:45 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 27 21:02:45 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 253242 Nov 27 21:02:45 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3f000000 vc_m Nov 27 21:02:45 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 bytes) Nov 27 21:02:45 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Nov 27 21:02:45 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Nov 27 21:02:45 volumio kernel: Memory: 976680K/1021952K available (7168K kernel code, 550K rwdata, 2048K rodata, 1024K init, 706K bss, 37080K reserved, 8192K cma-reserved) Nov 27 21:02:45 volumio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe600000 ( 998 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x80800000 (8160 kB) .init : 0x80b00000 - 0x80c00000 (1024 kB) .data : 0x80c00000 - 0x80c8986c ( 551 kB) .bss : 0x80c90f40 - 0x80d417f4 ( 707 kB) Nov 27 21:02:45 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 27 21:02:45 volumio kernel: ftrace: allocating 24917 entries in 74 pages Nov 27 21:02:45 volumio kernel: Hierarchical RCU implementation. Nov 27 21:02:45 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 27 21:02:45 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Nov 27 21:02:45 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Nov 27 21:02:45 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Nov 27 21:02:45 volumio kernel: Switching to timer-based delay loop, resolution 52ns Nov 27 21:02:45 volumio kernel: Console: colour dummy device 80x30 Nov 27 21:02:45 volumio kernel: console [tty1] enabled Nov 27 21:02:45 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Nov 27 21:02:45 volumio kernel: pid_max: default: 32768 minimum: 301 Nov 27 21:02:45 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 27 21:02:45 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 27 21:02:45 volumio kernel: Disabling memory control group subsystem Nov 27 21:02:45 volumio kernel: CPU: Testing write buffer coherency: ok Nov 27 21:02:45 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Nov 27 21:02:45 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c Nov 27 21:02:45 volumio kernel: Hierarchical SRCU implementation. Nov 27 21:02:45 volumio kernel: smp: Bringing up secondary CPUs ... Nov 27 21:02:45 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Nov 27 21:02:45 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002

16

Even with fresh card and older OS it won’t restart with large Drive attached.
Will build it fresh but no restart very strange.
Below is the logs broken into three sections because of the size restriction.

volumio@volumio:~$ lsusb Bus 001 Device 004: ID 0bc2:3322 Seagate RSS LLC Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub volumio@volumio:~$ sudo journalctl -- Logs begin at Tue 2018-11-27 22:42:46 UTC, end at Tue 2018-11-27 22:48:45 UTC Nov 27 22:42:46 volumio systemd-journal[173]: Runtime journal is using 4.0M (max Nov 27 22:42:46 volumio kernel: Booting Linux on physical CPU 0x0 Nov 27 22:42:46 volumio kernel: Linux version 4.14.29-v7+ (dc4@dc4-XPS13-9333) ( Nov 27 22:42:46 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv Nov 27 22:42:46 volumio kernel: CPU: div instructions available: patching divisi Nov 27 22:42:46 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT al Nov 27 22:42:46 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B R Nov 27 22:42:46 volumio kernel: Memory policy: Data cache writealloc Nov 27 22:42:46 volumio kernel: cma: Reserved 8 MiB at 0x3dc00000 Nov 27 22:42:46 volumio kernel: On node 0 totalpages: 255488 Nov 27 22:42:46 volumio kernel: free_area_init_node: node 0, pgdat 80c7edc0, nod Nov 27 22:42:46 volumio kernel: Normal zone: 2246 pages used for memmap Nov 27 22:42:46 volumio kernel: Normal zone: 0 pages reserved Nov 27 22:42:46 volumio kernel: Normal zone: 255488 pages, LIFO batch:31 Nov 27 22:42:46 volumio kernel: random: fast init done Nov 27 22:42:46 volumio kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r Nov 27 22:42:46 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17* Nov 27 22:42:46 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 27 22:42:46 volumio kernel: Built 1 zonelists, mobility grouping on. Total Nov 27 22:42:46 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb. Nov 27 22:42:46 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 by Nov 27 22:42:46 volumio kernel: Dentry cache hash table entries: 131072 (order: Nov 27 22:42:46 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, Nov 27 22:42:46 volumio kernel: Memory: 976680K/1021952K available (7168K kernel Nov 27 22:42:46 volumio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB fixmap : 0xffc00000 - 0xfff00000 (3072 kB vmalloc : 0xbe800000 - 0xff800000 (1040 MB lowmem : 0x80000000 - 0xbe600000 ( 998 MB modules : 0x7f000000 - 0x80000000 ( 16 MB .text : 0x80008000 - 0x80800000 (8160 kB .init : 0x80b00000 - 0x80c00000 (1024 kB .data : 0x80c00000 - 0x80c8986c ( 551 kB .bss : 0x80c90f40 - 0x80d417f4 ( 707 kB Nov 27 22:42:46 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs= Nov 27 22:42:46 volumio kernel: ftrace: allocating 24917 entries in 74 pages Nov 27 22:42:46 volumio kernel: Hierarchical RCU implementation. Nov 27 22:42:46 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 27 22:42:46 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (p Nov 27 22:42:46 volumio kernel: clocksource: arch_sys_counter: mask: 0xfffffffff Nov 27 22:42:46 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, Nov 27 22:42:46 volumio kernel: Switching to timer-based delay loop, resolution Nov 27 22:42:46 volumio kernel: Console: colour dummy device 80x30 Nov 27 22:42:46 volumio kernel: console [tty1] enabled Nov 27 22:42:46 volumio kernel: Calibrating delay loop (skipped), value calculat Nov 27 22:42:46 volumio kernel: pid_max: default: 32768 minimum: 301 Nov 27 22:42:46 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, Nov 27 22:42:46 volumio kernel: Mountpoint-cache hash table entries: 2048 (order Nov 27 22:42:46 volumio kernel: Disabling memory control group subsystem lines 28-50 fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe600000 ( 998 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x80800000 (8160 kB) .init : 0x80b00000 - 0x80c00000 (1024 kB) .data : 0x80c00000 - 0x80c8986c ( 551 kB) .bss : 0x80c90f40 - 0x80d417f4 ( 707 kB) Nov 27 22:42:46 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 27 22:42:46 volumio kernel: ftrace: allocating 24917 entries in 74 pages Nov 27 22:42:46 volumio kernel: Hierarchical RCU implementation. Nov 27 22:42:46 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 27 22:42:46 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Nov 27 22:42:46 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Nov 27 22:42:46 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Nov 27 22:42:46 volumio kernel: Switching to timer-based delay loop, resolution 52ns Nov 27 22:42:46 volumio kernel: Console: colour dummy device 80x30 Nov 27 22:42:46 volumio kernel: console [tty1] enabled Nov 27 22:42:46 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Nov 27 22:42:46 volumio kernel: pid_max: default: 32768 minimum: 301 Nov 27 22:42:46 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 27 22:42:46 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 27 22:42:46 volumio kernel: Disabling memory control group subsystem Nov 27 22:42:46 volumio kernel: CPU: Testing write buffer coherency: ok Nov 27 22:42:46 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Nov 27 22:42:46 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c Nov 27 22:42:46 volumio kernel: Hierarchical SRCU implementation. Nov 27 22:42:46 volumio kernel: smp: Bringing up secondary CPUs ... Nov 27 22:42:46 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Nov 27 22:42:46 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Nov 27 22:42:46 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Nov 27 22:42:46 volumio kernel: smp: Brought up 1 node, 4 CPUs Nov 27 22:42:46 volumio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Nov 27 22:42:46 volumio kernel: CPU: All CPU(s) started in HYP mode. Nov 27 22:42:46 volumio kernel: CPU: Virtualization extensions available. Nov 27 22:42:46 volumio kernel: devtmpfs: initialized Nov 27 22:42:46 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Nov 27 22:42:46 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Nov 27 22:42:46 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Nov 27 22:42:46 volumio kernel: pinctrl core: initialized pinctrl subsystem Nov 27 22:42:46 volumio kernel: NET: Registered protocol family 16 Nov 27 22:42:46 volumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Nov 27 22:42:46 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Nov 27 22:42:46 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Nov 27 22:42:46 volumio kernel: Serial: AMBA PL011 UART driver Nov 27 22:42:46 volumio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Nov 27 22:42:46 volumio kernel: uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe Nov 27 22:42:46 volumio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at be813000, dmachans=0x1 Nov 27 22:42:46 volumio kernel: SCSI subsystem initialized Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver usbfs Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver hub Nov 27 22:42:46 volumio kernel: usbcore: registered new device driver usb Nov 27 22:42:46 volumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2018-03-21 14:47 Nov 27 22:42:46 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Nov 27 22:42:46 volumio kernel: VFS: Disk quotas dquot_6.6.0 Nov 27 22:42:46 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Nov 27 22:42:46 volumio kernel: FS-Cache: Loaded Nov 27 22:42:46 volumio kernel: CacheFiles: Loaded Nov 27 22:42:46 volumio kernel: NET: Registered protocol family 2 Nov 27 22:42:46 volumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Nov 27 22:42:46 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Nov 27 22:42:46 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Nov 27 22:42:46 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Nov 27 22:42:46 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Nov 27 22:42:46 volumio kernel: NET: Registered protocol family 1 Nov 27 22:42:46 volumio kernel: RPC: Registered named UNIX socket transport module. Nov 27 22:42:46 volumio kernel: RPC: Registered udp transport module. Nov 27 22:42:46 volumio kernel: RPC: Registered tcp transport module. Nov 27 22:42:46 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 27 22:42:46 volumio kernel: Trying to unpack rootfs image as initramfs... Nov 27 22:42:46 volumio kernel: Freeing initrd memory: 14312K Nov 27 22:42:46 volumio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Nov 27 22:42:46 volumio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Nov 27 22:42:46 volumio kernel: FS-Cache: Netfs 'nfs' registered for caching Nov 27 22:42:46 volumio kernel: NFS: Registering the id_resolver key type Nov 27 22:42:46 volumio kernel: Key type id_resolver registered Nov 27 22:42:46 volumio kernel: Key type id_legacy registered Nov 27 22:42:46 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 27 22:42:46 volumio kernel: io scheduler noop registered (default) Nov 27 22:42:46 volumio kernel: io scheduler deadline registered Nov 27 22:42:46 volumio kernel: io scheduler cfq registered Nov 27 22:42:46 volumio kernel: io scheduler mq-deadline registered Nov 27 22:42:46 volumio kernel: io scheduler kyber registered Nov 27 22:42:46 volumio kernel: BCM2708FB: allocated DMA memory fdd10000 Nov 27 22:42:46 volumio kernel: BCM2708FB: allocated DMA channel 0 @ be813000 Nov 27 22:42:46 volumio kernel: Console: switching to colour frame buffer device 80x30 Nov 27 22:42:46 volumio kernel: bcm2835-rng 3f104000.rng: hwrng registered Nov 27 22:42:46 volumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB) Nov 27 22:42:46 volumio kernel: vc-sm: Videocore shared memory driver Nov 27 22:42:46 volumio kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Nov 27 22:42:46 volumio kernel: brd: module loaded Nov 27 22:42:46 volumio kernel: loop: module loaded Nov 27 22:42:46 volumio kernel: Loading iSCSI transport class v2.0-870. Nov 27 22:42:46 volumio kernel: libphy: Fixed MDIO Bus: probed Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver lan78xx Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver smsc95xx Nov 27 22:42:46 volumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 27 22:42:46 volumio kernel: Core Release: 2.80a Nov 27 22:42:46 volumio kernel: Setting default values for core params Nov 27 22:42:46 volumio kernel: Finished setting default values for core params Nov 27 22:42:46 volumio kernel: Using Buffer DMA mode Nov 27 22:42:46 volumio kernel: Periodic Transfer Interrupt Enhancement - disabled Nov 27 22:42:46 volumio kernel: Multiprocessor Interrupt Enhancement - disabled Nov 27 22:42:46 volumio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Nov 27 22:42:46 volumio kernel: Dedicated Tx FIFOs mode Nov 27 22:42:46 volumio kernel: WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbdd04000 dma = 0xfdd04000 len=9024 Nov 27 22:42:46 volumio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Nov 27 22:42:46 volumio kernel: dwc_otg: Microframe scheduler enabled Nov 27 22:42:46 volumio kernel: WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805d2694 Nov 27 22:42:46 volumio kernel: WARN::hcd_init_fiq:460: FIQ ASM at 0x805d29fc length 36 Nov 27 22:42:46 volumio kernel: WARN::hcd_init_fiq:486: MPHI regs_base at 0xbe87e000 Nov 27 22:42:46 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller Nov 27 22:42:46 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Nov 27 22:42:46 volumio kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000 Nov 27 22:42:46 volumio kernel: Init: Port Power? op_state=1 Nov 27 22:42:46 volumio kernel: Init: Power Port (0) Nov 27 22:42:46 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Nov 27 22:42:46 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 27 22:42:46 volumio kernel: usb usb1: Product: DWC OTG Controller Nov 27 22:42:46 volumio kernel: usb usb1: Manufacturer: Linux 4.14.29-v7+ dwc_otg_hcd Nov 27 22:42:46 volumio kernel: usb usb1: SerialNumber: 3f980000.usb Nov 27 22:42:46 volumio kernel: hub 1-0:1.0: USB hub found Nov 27 22:42:46 volumio kernel: hub 1-0:1.0: 1 port detected Nov 27 22:42:46 volumio kernel: dwc_otg: FIQ enabled Nov 27 22:42:46 volumio kernel: dwc_otg: NAK holdoff enabled Nov 27 22:42:46 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 27 22:42:46 volumio kernel: Module dwc_common_port init Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver usb-storage Nov 27 22:42:46 volumio kernel: mousedev: PS/2 mouse device common for all mice Nov 27 22:42:46 volumio kernel: IR NEC protocol handler initialized Nov 27 22:42:46 volumio kernel: IR RC5(x/sz) protocol handler initialized Nov 27 22:42:46 volumio kernel: IR RC6 protocol handler initialized Nov 27 22:42:46 volumio kernel: IR JVC protocol handler initialized Nov 27 22:42:46 volumio kernel: IR Sony protocol handler initialized Nov 27 22:42:46 volumio kernel: IR SANYO protocol handler initialized Nov 27 22:42:46 volumio kernel: IR Sharp protocol handler initialized Nov 27 22:42:46 volumio kernel: IR MCE Keyboard/mouse protocol handler initialized Nov 27 22:42:46 volumio kernel: IR XMP protocol handler initialized Nov 27 22:42:46 volumio kernel: bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer Nov 27 22:42:46 volumio kernel: bcm2835-cpufreq: min=600000 max=1200000 Nov 27 22:42:46 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Nov 27 22:42:46 volumio kernel: sdhci: Copyright(c) Pierre Ossman Nov 27 22:42:46 volumio kernel: mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe Nov 27 22:42:46 volumio kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Nov 27 22:42:46 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 27 22:42:46 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 27 22:42:46 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Nov 27 22:42:46 volumio kernel: usbcore: registered new interface driver usbhid Nov 27 22:42:46 volumio kernel: usbhid: USB HID core driver Nov 27 22:42:46 volumio kernel: vchiq: vchiq_init_state: slot_zero = bdd80000, is_master = 0 Nov 27 22:42:46 volumio kernel: [vc_sm_connected_init]: start Nov 27 22:42:46 volumio kernel: vc_vchi_sm_init: failed to open VCHI service (-1) Nov 27 22:42:46 volumio kernel: [vc_sm_connected_init]: failed to initialize shared memory service Nov 27 22:42:46 volumio kernel: [vc_sm_connected_init]: end - returning -1 Nov 27 22:42:46 volumio kernel: Initializing XFRM netlink socket Nov 27 22:42:46 volumio kernel: NET: Registered protocol family 17 Nov 27 22:42:46 volumio kernel: Key type dns_resolver registered Nov 27 22:42:46 volumio kernel: Registering SWP/SWPB emulation handler Nov 27 22:42:46 volumio kernel: registered taskstats version 1 Nov 27 22:42:46 volumio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Nov 27 22:42:46 volumio kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 Nov 27 22:42:46 volumio kernel: mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 Nov 27 22:42:46 volumio kernel: mmc-bcm2835 3f300000.mmc: DMA channel allocated Nov 27 22:42:46 volumio kernel: sdhost: log_buf @ bdd07000 (fdd07000) Nov 27 22:42:46 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Nov 27 22:42:46 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 27 22:42:46 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 27 22:42:46 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Nov 27 22:42:46 volumio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Nov 27 22:42:46 volumio kernel: of_cfs_init Nov 27 22:42:46 volumio kernel: of_cfs_init: OK Nov 27 22:42:46 volumio kernel: Freeing unused kernel memory: 1024K Nov 27 22:42:46 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 27 22:42:46 volumio kernel: mmc1: new high speed SDIO card at address 0001 Nov 27 22:42:46 volumio initramfs: Booting Volumio for for BCM2835 Nov 27 22:42:46 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Nov 27 22:42:46 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Nov 27 22:42:46 volumio kernel: mmc0: new high speed SDHC card at address aaaa Nov 27 22:42:46 volumio kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Nov 27 22:42:46 volumio kernel: mmcblk0: p1 p2 p3 Nov 27 22:42:46 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 27 22:42:46 volumio initramfs: IMGPART=/dev/mmcblk0p2 Nov 27 22:42:46 volumio initramfs: IMGFILE=/volumio_current.sqsh Nov 27 22:42:46 volumio initramfs: Boot delay (except first time) will be 5 seconds Nov 27 22:42:46 volumio initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds Nov 27 22:42:46 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Nov 27 22:42:46 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 27 22:42:46 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Nov 27 22:42:46 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 22:42:46 volumio kernel: hub 1-1:1.0: USB hub found Nov 27 22:42:46 volumio kernel: hub 1-1:1.0: 5 ports detected Nov 27 22:42:46 volumio initramfs: /dev/mmcblk0p2: Nov 27 22:42:46 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Nov 27 22:42:46 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 27 22:42:46 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Nov 27 22:42:46 volumio initramfs: Doing a 5 second delay here to give kernel load a headstart Nov 27 22:42:46 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Nov 27 22:42:46 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 27 22:42:46 volumio kernel: smsc95xx v1.0.6 Nov 27 22:42:46 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:21:43:9f Nov 27 22:42:46 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Nov 27 22:42:46 volumio kernel: usb 1-1.3: New USB device found, idVendor=0bc2, idProduct=3322 Nov 27 22:42:46 volumio kernel: usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Nov 27 22:42:46 volumio kernel: usb 1-1.3: Product: Expansion Desk Nov 27 22:42:46 volumio kernel: usb 1-1.3: Manufacturer: Seagate Nov 27 22:42:46 volumio kernel: usb 1-1.3: SerialNumber: NA8FHGJJ Nov 27 22:42:46 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Nov 27 22:42:46 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Nov 27 22:42:46 volumio kernel: scsi 0:0:0:0: Direct-Access Seagate Expansion Desk 9401 PQ: 0 ANSI: 6 Nov 27 22:42:46 volumio kernel: sd 0:0:0:0: [sda] Spinning up disk... Nov 27 22:42:46 volumio kernel: . Nov 27 22:42:46 volumio kernel: . Nov 27 22:42:46 volumio initramfs: Checking for USB updates Nov 27 22:42:46 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Nov 27 22:42:46 volumio kernel: . Nov 27 22:42:46 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 27 22:42:46 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Nov 27 22:42:46 volumio initramfs: With Option: Nov 27 22:42:46 volumio initramfs: VOLUMIO_VERSION="2.389" Nov 27 22:42:46 volumio initramfs: Finish initramfs, continue booting Volumio Nov 27 22:42:46 volumio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Nov 27 22:42:46 volumio systemd[1]: Detected architecture 'arm'. Nov 27 22:42:46 volumio kernel: NET: Registered protocol family 10 Nov 27 22:42:46 volumio kernel: Segment Routing with IPv6 Nov 27 22:42:46 volumio systemd[1]: Inserted module 'ipv6' Nov 27 22:42:46 volumio systemd[1]: Set hostname to <volumio>. Nov 27 22:42:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Nov 27 22:42:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Nov 27 22:42:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Nov 27 22:42:46 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Nov 27 22:42:46 volumio systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Nov 27 22:42:46 volumio systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Nov 27 22:42:46 volumio kernel: . Nov 27 22:42:46 volumio kernel: fuse init (API version 7.26) Nov 27 22:42:46 volumio systemd-udevd[180]: starting version 215 Nov 27 22:42:46 volumio systemd-journal[173]: Journal started Nov 27 22:42:46 volumio systemd[1]: Starting Slices. Nov 27 22:42:46 volumio systemd[1]: Reached target Slices. Nov 27 22:42:46 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Nov 27 22:42:46 volumio systemd[1]: Started Restore / save the current clock. Nov 27 22:42:46 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. Nov 27 22:42:46 volumio systemd[1]: Time has been changed Nov 27 22:42:46 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Nov 27 22:42:46 volumio systemd[1]: Started Create Static Device Nodes in /dev. Nov 27 22:42:46 volumio systemd[1]: Starting udev Kernel Device Manager... Nov 27 22:42:46 volumio systemd[1]: Mounted POSIX Message Queue File System. Nov 27 22:42:46 volumio systemd[1]: Started Remount Root and Kernel File Systems. Nov 27 22:42:46 volumio systemd-modules-load[171]: Inserted module 'fuse' Nov 27 22:42:46 volumio systemd[1]: Starting Copy rules generated while the root was ro... Nov 27 22:42:46 volumio systemd[1]: Started Various fixups to make systemd work better on Debian. Nov 27 22:42:46 volumio systemd[1]: Starting Load/Save Random Seed... Nov 27 22:42:46 volumio systemd[1]: Starting Local File Systems (Pre). Nov 27 22:42:46 volumio systemd[1]: Reached target Local File Systems (Pre). Nov 27 22:42:46 volumio systemd[1]: Mounting /tmp... Nov 27 22:42:46 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 27 22:42:46 volumio systemd[1]: Mounting /var/spool/cups... Nov 27 22:42:46 volumio systemd[1]: Mounting /var/log... Nov 27 22:42:46 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 27 22:42:46 volumio systemd[1]: Mounted /tmp. Nov 27 22:42:46 volumio systemd[1]: Started Copy rules generated while the root was ro. Nov 27 22:42:46 volumio fake-hwclock[159]: Tue Nov 27 22:42:46 UTC 2018 Nov 27 22:42:46 volumio systemd-modules-load[171]: Inserted module 'i2c_dev' Nov 27 22:42:46 volumio kernel: i2c /dev entries driver Nov 27 22:42:46 volumio systemd[1]: Started Load Kernel Modules. Nov 27 22:42:46 volumio systemd[1]: Started Load/Save Random Seed. Nov 27 22:42:46 volumio systemd[1]: Mounted /var/spool/cups. Nov 27 22:42:46 volumio systemd[1]: Mounted /var/log. Nov 27 22:42:46 volumio systemd[1]: Started Show Plymouth Boot Screen. Nov 27 22:42:46 volumio systemd[1]: Mounted FUSE Control File System. Nov 27 22:42:46 volumio systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Nov 27 22:42:46 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 27 22:42:46 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 27 22:42:46 volumio systemd[1]: Starting Paths. Nov 27 22:42:46 volumio systemd[1]: Reached target Paths. Nov 27 22:42:46 volumio systemd[1]: Mounting /var/spool/cups/tmp... Nov 27 22:42:46 volumio systemd[1]: Mounting Configuration File System... Nov 27 22:42:46 volumio systemd[1]: Starting Apply Kernel Variables... Nov 27 22:42:46 volumio systemd[1]: Mounted /var/spool/cups/tmp. Nov 27 22:42:46 volumio systemd[1]: Mounted Configuration File System. Nov 27 22:42:46 volumio systemd[1]: Started Apply Kernel Variables. Nov 27 22:42:46 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 27 22:42:46 volumio kernel: snd_bcm2835: unknown parameter 'index' ignored Nov 27 22:42:46 volumio kernel: bcm2835_alsa bcm2835_alsa: card created with 8 channels Nov 27 22:42:47 volumio kernel: . Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: snd_soc_register_card() failed: -517 Nov 27 22:42:47 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Nov 27 22:42:47 volumio systemd[1]: Starting Sound Card. Nov 27 22:42:47 volumio systemd[1]: Reached target Sound Card. Nov 27 22:42:47 volumio systemd[1]: Found device /dev/mmcblk0p1. Nov 27 22:42:47 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: snd_soc_register_card() failed: -517

17

Nov 27 22:42:47 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Nov 27 22:42:47 volumio kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001 Nov 27 22:42:47 volumio kernel: usbcore: registered new interface driver brcmfmac Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: snd_soc_register_card() failed: -517 Nov 27 22:42:47 volumio kernel: pcm512x 1-004d: 1-004d supply AVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: pcm512x 1-004d: 1-004d supply DVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: pcm512x 1-004d: 1-004d supply CPVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: snd_soc_register_card() failed: -517 Nov 27 22:42:47 volumio kernel: pcm512x 1-004c: 1-004c supply AVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: pcm512x 1-004c: 1-004c supply DVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: pcm512x 1-004c: 1-004c supply CPVDD not found, using dummy regulator Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: control 2:0:0:Digital Playback Volume:0 is already present Nov 27 22:42:47 volumio kernel: pcm512x 1-004d: ASoC: Failed to add Digital Playback Volume: -16 Nov 27 22:42:47 volumio kernel: snd-allo-piano-dac-plus soc:sound: multicodec <-> 3f203000.i2s mapping ok Nov 27 22:42:47 volumio systemd[1]: Starting system-ifup.slice. Nov 27 22:42:47 volumio systemd[1]: Created slice system-ifup.slice. Nov 27 22:42:47 volumio systemd-fsck[313]: fsck.fat 3.0.27 (2014-11-12) Nov 27 22:42:47 volumio systemd-fsck[313]: /dev/mmcblk0p1: 161 files, 72519/123044 clusters Nov 27 22:42:47 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Nov 27 22:42:47 volumio systemd[1]: Mounting /boot... Nov 27 22:42:47 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f Nov 27 22:42:47 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.39 Inc Nov 27 22:42:47 volumio systemd[1]: Mounted /boot. Nov 27 22:42:47 volumio systemd[1]: Starting system-systemd\x2drfkill.slice. Nov 27 22:42:47 volumio systemd[1]: Created slice system-systemd\x2drfkill.slice. Nov 27 22:42:47 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Nov 27 22:42:47 volumio systemd[1]: Starting Local File Systems. Nov 27 22:42:47 volumio systemd[1]: Reached target Local File Systems. Nov 27 22:42:47 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 27 22:42:47 volumio systemd[1]: Starting Remote File Systems. Nov 27 22:42:47 volumio systemd[1]: Reached target Remote File Systems. Nov 27 22:42:47 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Nov 27 22:42:47 volumio systemd[1]: Starting Create Volatile Files and Directories... Nov 27 22:42:47 volumio systemd[1]: Starting LSB: Raise network interfaces.... Nov 27 22:42:47 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Nov 27 22:42:47 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 27 22:42:47 volumio systemd[1]: Started Create Volatile Files and Directories. Nov 27 22:42:47 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Nov 27 22:42:48 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 27 22:42:48 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 27 22:42:48 volumio kernel: . Nov 27 22:42:48 volumio kernel: brcmfmac: power management disabled Nov 27 22:42:49 volumio systemd[1]: Received SIGRTMIN+20 from PID 206 (plymouthd). Nov 27 22:42:49 volumio kernel: . Nov 27 22:42:49 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Nov 27 22:42:49 volumio kernel: brcmfmac: power management disabled Nov 27 22:42:49 volumio networking[344]: Configuring network interfaces...dhcpcd[440]: version 6.0.5 starting Nov 27 22:42:49 volumio dhcpcd[440]: version 6.0.5 starting Nov 27 22:42:50 volumio kernel: . Nov 27 22:42:50 volumio kernel: ready Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] 5860533167 512-byte logical blocks: (3.00 TB/2.73 TiB) Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 4f 00 00 00 Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). Nov 27 22:42:50 volumio kernel: random: crng init done Nov 27 22:42:50 volumio dhcpcd[440]: eth0: soliciting a DHCP lease Nov 27 22:42:50 volumio networking[344]: dhcpcd[440]: eth0: soliciting a DHCP lease Nov 27 22:42:50 volumio kernel: sda: sda1 sda2 Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Nov 27 22:42:50 volumio networking[344]: dhcpcd[440]: eth0: carrier lost Nov 27 22:42:50 volumio dhcpcd[440]: eth0: carrier lost Nov 27 22:42:51 volumio dhcpcd[440]: eth0: carrier acquired Nov 27 22:42:51 volumio networking[344]: dhcpcd[440]: eth0: carrier acquired Nov 27 22:42:51 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1 Nov 27 22:42:51 volumio dhcpcd[440]: eth0: soliciting a DHCP lease Nov 27 22:42:51 volumio networking[344]: dhcpcd[440]: eth0: soliciting a DHCP lease Nov 27 22:42:51 volumio dhcpcd[440]: eth0: offered 192.168.0.13 from 192.168.0.1 Nov 27 22:42:51 volumio networking[344]: dhcpcd[440]: eth0: offered 192.168.0.13 from 192.168.0.1 Nov 27 22:42:52 volumio dhcpcd[440]: eth0: leased 192.168.0.13 for 86400 seconds Nov 27 22:42:52 volumio dhcpcd[440]: eth0: adding host route to 192.168.0.13 via 127.0.0.1 Nov 27 22:42:52 volumio dhcpcd[440]: eth0: adding route to 192.168.0.0/24 Nov 27 22:42:52 volumio dhcpcd[440]: eth0: adding default route via 192.168.0.1 Nov 27 22:42:52 volumio networking[344]: dhcpcd[440]: eth0: leased 192.168.0.13 for 86400 seconds Nov 27 22:42:52 volumio networking[344]: dhcpcd[440]: eth0: adding host route to 192.168.0.13 via 127.0.0.1 Nov 27 22:42:52 volumio networking[344]: dhcpcd[440]: eth0: adding route to 192.168.0.0/24 Nov 27 22:42:52 volumio networking[344]: dhcpcd[440]: eth0: adding default route via 192.168.0.1 Nov 27 22:42:52 volumio dhcpcd[440]: forked to background, child pid 510 Nov 27 22:42:52 volumio networking[344]: dhcpcd[440]: forked to background, child pid 510 Nov 27 22:42:53 volumio networking[344]: done. Nov 27 22:42:53 volumio systemd[1]: Started LSB: Raise network interfaces.. Nov 27 22:42:53 volumio systemd[1]: Starting ifup for wlan0... Nov 27 22:42:53 volumio systemd[1]: Started ifup for wlan0. Nov 27 22:42:53 volumio systemd[1]: Starting ifup for eth0... Nov 27 22:42:53 volumio systemd[1]: Started ifup for eth0. Nov 27 22:42:53 volumio systemd[1]: Starting Network. Nov 27 22:42:53 volumio systemd[1]: Reached target Network. Nov 27 22:42:53 volumio systemd[1]: Starting Network is Online. Nov 27 22:42:53 volumio systemd[1]: Reached target Network is Online. Nov 27 22:42:53 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Nov 27 22:42:53 volumio ifup[557]: /sbin/ifup: interface eth0 already configured Nov 27 22:42:53 volumio rpcbind[559]: Starting rpcbind daemon.... Nov 27 22:42:53 volumio systemd[1]: Started LSB: RPC portmapper replacement. Nov 27 22:42:53 volumio systemd[1]: Starting RPC Port Mapper. Nov 27 22:42:53 volumio systemd[1]: Reached target RPC Port Mapper. Nov 27 22:42:53 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Nov 27 22:42:53 volumio rpc.statd[641]: Version 1.2.8 starting Nov 27 22:42:53 volumio sm-notify[642]: Version 1.2.8 starting Nov 27 22:42:53 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Nov 27 22:42:53 volumio nfs-common[618]: Starting NFS common utilities: statd idmapd. Nov 27 22:42:53 volumio systemd[1]: Started LSB: NFS support files common to client and server. Nov 27 22:42:53 volumio systemd[1]: Starting System Initialization. Nov 27 22:42:53 volumio systemd[1]: Reached target System Initialization. Nov 27 22:42:53 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Nov 27 22:42:53 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 27 22:42:53 volumio systemd[1]: Starting mpd.socket. Nov 27 22:42:53 volumio systemd[1]: Listening on mpd.socket. Nov 27 22:42:53 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Nov 27 22:42:53 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 27 22:42:53 volumio systemd[1]: Starting Sockets. Nov 27 22:42:53 volumio systemd[1]: Reached target Sockets. Nov 27 22:42:53 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Nov 27 22:42:53 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 27 22:42:53 volumio systemd[1]: Starting Timers. Nov 27 22:42:53 volumio systemd[1]: Reached target Timers. Nov 27 22:42:53 volumio systemd[1]: Started Manage Sound Card State (restore and store). Nov 27 22:42:53 volumio systemd[1]: Starting Restore Sound Card State... Nov 27 22:42:53 volumio systemd[1]: Starting Basic System. Nov 27 22:42:53 volumio systemd[1]: Reached target Basic System. Nov 27 22:42:53 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Nov 27 22:42:53 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 27 22:42:53 volumio systemd[1]: Starting Volumio Iptables Module... Nov 27 22:42:53 volumio systemd[1]: Started Volumio Iptables Module. Nov 27 22:42:53 volumio systemd[1]: Starting mount disks automatically with standby... Nov 27 22:42:53 volumio systemd[1]: Started mount disks automatically with standby. Nov 27 22:42:53 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Nov 27 22:42:53 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 27 22:42:53 volumio systemd[1]: Starting volumio-remote-updater.service... Nov 27 22:42:53 volumio systemd[1]: Started volumio-remote-updater.service. Nov 27 22:42:53 volumio systemd[1]: Starting Volumio SSH enabler... Nov 27 22:42:53 volumio systemd[1]: Started Volumio SSH enabler. Nov 27 22:42:53 volumio systemd[1]: Starting Wireless Services... Nov 27 22:42:53 volumio systemd[1]: Starting OpenBSD Secure Shell server... Nov 27 22:42:53 volumio systemd[1]: Starting /etc/rc.local Compatibility... Nov 27 22:42:53 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Nov 27 22:42:53 volumio systemd[1]: Starting Login Service... Nov 27 22:42:53 volumio systemd[1]: Starting LSB: start Winbind daemon... Nov 27 22:42:53 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Nov 27 22:42:53 volumio systemd[1]: Starting LSB: Start NTP daemon... Nov 27 22:42:53 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Nov 27 22:42:54 volumio systemd[1]: Starting Restart Volumio Network... Nov 27 22:42:54 volumio systemd[1]: Started Restart Volumio Network. Nov 27 22:42:54 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Nov 27 22:42:54 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 27 22:42:54 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Nov 27 22:42:54 volumio echo[675]: Start Nov 27 22:42:54 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Nov 27 22:42:54 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Nov 27 22:42:54 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 27 22:42:54 volumio systemd[1]: Starting Music Player Daemon... Nov 27 22:42:54 volumio systemd[1]: Starting D-Bus System Message Bus... Nov 27 22:42:54 volumio systemd[1]: Started D-Bus System Message Bus. Nov 27 22:42:54 volumio dhcpcd[707]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Nov 27 22:42:54 volumio dhcpcd[707]: /var/run/dhcpcd-eth0.pid ... failed! Nov 27 22:42:54 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Nov 27 22:42:54 volumio loadcpufreq[679]: Loading cpufreq kernel modules...done (none). Nov 27 22:42:54 volumio avahi-daemon[713]: Found user 'avahi' (UID 104) and group 'avahi' (GID 105). Nov 27 22:42:54 volumio avahi-daemon[713]: Successfully dropped root privileges. Nov 27 22:42:54 volumio avahi-daemon[713]: avahi-daemon 0.6.31 starting up. Nov 27 22:42:54 volumio kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Nov 27 22:42:54 volumio ntpd[734]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Nov 27 22:42:54 volumio ntp[673]: Starting NTP server: ntpd. Nov 27 22:42:54 volumio sudo[666]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Nov 27 22:42:54 volumio sudo[666]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:42:54 volumio ntpd[761]: proto: precision = 1.041 usec Nov 27 22:42:54 volumio ntpd[761]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Nov 27 22:42:54 volumio sudo[666]: pam_unix(sudo:session): session closed for user root Nov 27 22:42:54 volumio ntpd[761]: Listen and drop on 1 v6wildcard :: UDP 123 Nov 27 22:42:54 volumio ntpd[761]: Listen normally on 2 lo 127.0.0.1 UDP 123 Nov 27 22:42:54 volumio ntpd[761]: Listen normally on 3 eth0 192.168.0.13 UDP 123 Nov 27 22:42:54 volumio ntpd[761]: peers refreshed Nov 27 22:42:54 volumio ntpd[761]: Listening on routing socket on fd #20 for interface updates Nov 27 22:42:54 volumio avahi-daemon[713]: Successfully called chroot(). Nov 27 22:42:54 volumio avahi-daemon[713]: Successfully dropped remaining capabilities. Nov 27 22:42:54 volumio avahi-daemon[713]: Loading service file /services/volumio.service. Nov 27 22:42:54 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 27 22:42:54 volumio systemd[1]: Starting Permit User Sessions... Nov 27 22:42:54 volumio systemd[1]: Started Restore Sound Card State. Nov 27 22:42:54 volumio avahi-daemon[713]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.13. Nov 27 22:42:54 volumio avahi-daemon[713]: New relevant interface eth0.IPv4 for mDNS. Nov 27 22:42:54 volumio avahi-daemon[713]: Network interface enumeration completed. Nov 27 22:42:54 volumio avahi-daemon[713]: Registering new address record for 192.168.0.13 on eth0.IPv4. Nov 27 22:42:54 volumio avahi-daemon[713]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Nov 27 22:42:54 volumio systemd[1]: Started /etc/rc.local Compatibility. Nov 27 22:42:54 volumio systemd[1]: Started LSB: Start NTP daemon. Nov 27 22:42:55 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 27 22:42:55 volumio systemd[1]: Started OpenBSD Secure Shell server. Nov 27 22:42:55 volumio systemd[1]: Started LSB: Brings up/down network automatically. Nov 27 22:42:55 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Nov 27 22:42:55 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Nov 27 22:42:55 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Nov 27 22:42:55 volumio systemd[1]: Unit dhcpcd.service entered failed state. Nov 27 22:42:55 volumio systemd[1]: Started Permit User Sessions. Nov 27 22:42:55 volumio sshd[779]: Server listening on 0.0.0.0 port 22. Nov 27 22:42:55 volumio sshd[779]: Server listening on :: port 22. Nov 27 22:42:55 volumio dbus[716]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Nov 27 22:42:55 volumio systemd-logind[670]: New seat seat0. Nov 27 22:42:55 volumio systemd[1]: Started Login Service. Nov 27 22:42:55 volumio systemd[1]: Starting Disk Manager (legacy version)... Nov 27 22:42:55 volumio systemd[1]: Stopping Wireless Services... Nov 27 22:42:55 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 27 22:42:55 volumio systemd[1]: Starting Hold until boot process finishes up... Nov 27 22:42:55 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 27 22:42:55 volumio systemd[1]: Starting Wireless Services... Nov 27 22:42:55 volumio systemd[1]: Received SIGRTMIN+21 from PID 206 (plymouthd). Nov 27 22:42:55 volumio systemd[1]: Started Hold until boot process finishes up. Nov 27 22:42:55 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Nov 27 22:42:55 volumio dbus[716]: [system] Successfully activated service 'org.freedesktop.UDisks' Nov 27 22:42:55 volumio sudo[792]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Nov 27 22:42:55 volumio systemd[1]: Started Disk Manager (legacy version). Nov 27 22:42:55 volumio systemd[1]: Starting Getty on tty1... Nov 27 22:42:55 volumio sudo[792]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:42:55 volumio systemd[1]: Started Getty on tty1. Nov 27 22:42:55 volumio cpufrequtils[782]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Nov 27 22:42:55 volumio sudo[792]: pam_unix(sudo:session): session closed for user root Nov 27 22:42:55 volumio systemd[1]: Starting Login Prompts. Nov 27 22:42:55 volumio systemd[1]: Reached target Login Prompts. Nov 27 22:42:55 volumio cpufrequtils[782]: CPU0...CPU1...CPU2...CPU3...done. Nov 27 22:42:55 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 27 22:42:55 volumio dbus[716]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Nov 27 22:42:55 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Nov 27 22:42:55 volumio avahi-daemon[713]: Server startup complete. Host name is volumio.local. Local service cookie is 4063533953. Nov 27 22:42:55 volumio polkitd[816]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 27 22:42:55 volumio dbus[716]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 27 22:42:55 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Nov 27 22:42:55 volumio volumio-remote-updater[663]: Error: No active session Nov 27 22:42:55 volumio volumio-remote-updater[663]: [2018-11-27 22:42:55] [info] asio async_connect error: system:111 (Connection refused) Nov 27 22:42:55 volumio volumio-remote-updater[663]: [2018-11-27 22:42:55] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 27 22:42:55 volumio volumio-remote-updater[663]: [2018-11-27 22:42:55] [error] handle_connect error: Underlying Transport Error Nov 27 22:42:56 volumio udisks-glue[661]: Device file /dev/sda2 inserted Nov 27 22:42:56 volumio udisks-glue[661]: Trying to automount /dev/sda2... Nov 27 22:42:56 volumio volumio[662]: Could not open config: /tmp/upmpdcli.conf Nov 27 22:42:56 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Nov 27 22:42:56 volumio systemd[1]: Unit upmpdcli.service entered failed state. Nov 27 22:42:56 volumio haveged[659]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Nov 27 22:42:56 volumio haveged[659]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Nov 27 22:42:56 volumio haveged[659]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99944 Nov 27 22:42:56 volumio haveged[659]: haveged: fills: 0, generated: 0 Nov 27 22:42:56 volumio avahi-daemon[713]: Service "Volumio" (/services/volumio.service) successfully established. Nov 27 22:42:57 volumio kernel: Under-voltage detected! (0x00050005) Nov 27 22:42:59 volumio wireless.js[811]: WIRELESS DAEMON: start Nov 27 22:42:59 volumio wireless.js[811]: WIRELESS: Loaded configuration Nov 27 22:42:59 volumio wireless.js[811]: Cleaning previous... Nov 27 22:42:59 volumio systemd[1]: Stopped hotspot.service. Nov 27 22:42:59 volumio sudo[840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 27 22:42:59 volumio sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:42:59 volumio sudo[840]: pam_unix(sudo:session): session closed for user root Nov 27 22:42:59 volumio sudo[848]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 27 22:42:59 volumio sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:43:00 volumio sudo[848]: pam_unix(sudo:session): session closed for user root Nov 27 22:43:00 volumio wireless.js[811]: Stopped aP Nov 27 22:43:00 volumio wireless.js[811]: Start wireless flow Nov 27 22:43:00 volumio wireless.js[811]: Stopped hotspot (if there).. Nov 27 22:43:00 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Nov 27 22:43:00 volumio sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 27 22:43:00 volumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:43:00 volumio sudo[862]: pam_unix(sudo:session): session closed for user root Nov 27 22:43:00 volumio sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 27 22:43:00 volumio sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:43:00 volumio sudo[878]: pam_unix(sudo:session): session closed for user root Nov 27 22:43:00 volumio wireless.js[811]: DHCP IP Nov 27 22:43:00 volumio wireless.js[811]: Start ap Nov 27 22:43:00 volumio sudo[890]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 27 22:43:00 volumio volumio-remote-updater[663]: [2018-11-27 22:43:00] [info] asio async_connect error: system:111 (Connection refused) Nov 27 22:43:00 volumio volumio-remote-updater[663]: [2018-11-27 22:43:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 27 22:43:00 volumio volumio-remote-updater[663]: [2018-11-27 22:43:00] [error] handle_connect error: Underlying Transport Error Nov 27 22:43:00 volumio sudo[890]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:43:00 volumio dhcpcd[895]: version 6.0.5 starting Nov 27 22:43:01 volumio kernel: brcmfmac: power management disabled Nov 27 22:43:01 volumio wpa_supplicant[887]: Successfully initialized wpa_supplicant Nov 27 22:43:01 volumio winbind[671]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Nov 27 22:43:01 volumio winbind[671]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 27 22:43:01 volumio winbind[671]: Unable to setup corepath for winbindd: No such file or directory Nov 27 22:43:01 volumio winbind[671]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 27 22:43:01 volumio winbind[671]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 27 22:43:01 volumio winbind[671]: Unable to setup corepath for winbindd: No such file or directory Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbind[671]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbind[671]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../source3/winbindd/winbindd.c:1549(main) Nov 27 22:43:01 volumio winbind[671]: winbindd version 4.2.14-Debian started. Nov 27 22:43:01 volumio winbind[671]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../lib/util/util.c:220(directory_create_or_exist) Nov 27 22:43:01 volumio winbind[671]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Nov 27 22:43:01 volumio winbind[671]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Nov 27 22:43:01 volumio winbind[671]: Unable to setup corepath for winbindd: No such file or directory Nov 27 22:43:01 volumio winbindd[874]: [2018/11/27 22:43:01.157126, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbindd[874]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio winbindd[874]: [2018/11/27 22:43:01.159042, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbindd[874]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio winbindd[909]: [2018/11/27 22:43:01.182236, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Nov 27 22:43:01 volumio winbindd[909]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 27 22:43:01 volumio nmbd[891]: [2018/11/27 22:43:01.191552, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[891]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio nmbd[891]: [2018/11/27 22:43:01.194898, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[891]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio nmbd[913]: [2018/11/27 22:43:01.200996, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Nov 27 22:43:01 volumio nmbd[913]: started asyncdns process 914 Nov 27 22:43:01 volumio nmbd[913]: [2018/11/27 22:43:01.229221, 0] ../lib/util/become_daemon.c:124(daemon_ready) Nov 27 22:43:01 volumio nmbd[913]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 27 22:43:01 volumio systemd[1]: Started LSB: start Winbind daemon. Nov 27 22:43:01 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Nov 27 22:43:01 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01.157126, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbind[671]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio winbind[671]: [2018/11/27 22:43:01.159042, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbind[671]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 27 22:43:01 volumio nmbd[674]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Nov 27 22:43:01 volumio nmbd[674]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 27 22:43:01 volumio nmbd[674]: Unable to setup corepath for nmbd: No such file or directory Nov 27 22:43:01 volumio nmbd[674]: [2018/11/27 22:43:01, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[674]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio nmbd[674]: [2018/11/27 22:43:01, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[674]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio nmbd[674]: [2018/11/27 22:43:01, 0] ../source3/nmbd/nmbd.c:908(main) Nov 27 22:43:01 volumio nmbd[674]: nmbd version 4.2.14-Debian started. Nov 27 22:43:01 volumio nmbd[674]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Nov 27 22:43:01 volumio nmbd[674]: [2018/11/27 22:43:01.191552, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[674]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio nmbd[674]: [2018/11/27 22:43:01.194898, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio nmbd[674]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 27 22:43:01 volumio winbindd[909]: [2018/11/27 22:43:01.248696, 0] ../lib/util/become_daemon.c:124(daemon_ready) Nov 27 22:43:01 volumio winbindd[909]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 27 22:43:01 volumio winbindd[920]: [2018/11/27 22:43:01.256765, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:01 volumio winbindd[920]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Nov 27 22:43:01 volumio winbind[671]: . Nov 27 22:43:01 volumio nmbd[674]: . Nov 27 22:43:01 volumio ntfs-3g[930]: Version 2014.2.15AR.2 integrated FUSE 28 Nov 27 22:43:01 volumio ntfs-3g[930]: Mounted /dev/sda2 (Read-Write, label "Seagate Expansion Drive", NTFS 3.1) Nov 27 22:43:01 volumio ntfs-3g[930]: Cmdline options: rw,nodev,nosuid,noatime,uid=1000,gid=1000,dmask=0077,fmask=0177,dmask=0000,fmask=0000,uhelper=udisks Nov 27 22:43:01 volumio ntfs-3g[930]: Mount options: rw,nodev,nosuid,uhelper=udisks,allow_other,nonempty,noatime,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Nov 27 22:43:01 volumio ntfs-3g[930]: Global ownership and permissions enforced, configuration type 7 Nov 27 22:43:01 volumio udisks-glue[661]: Successfully automounted /dev/sda2 at /media/Seagate Expansion Drive Nov 27 22:43:01 volumio udisks-glue[661]: Device file /dev/sda inserted Nov 27 22:43:01 volumio dhcpcd[895]: eth0: rebinding lease of 192.168.0.13 Nov 27 22:43:01 volumio dhcpcd[895]: wlan0: waiting for carrier Nov 27 22:43:01 volumio udisks-glue[661]: Device file /dev/mmcblk0p3 inserted Nov 27 22:43:01 volumio udisks-glue[661]: Device file /dev/mmcblk0 inserted Nov 27 22:43:01 volumio udisks-glue[661]: Device file /dev/sda1 inserted Nov 27 22:43:01 volumio udisks-glue[661]: Device file /dev/sda2 mounted at /media/Seagate Expansion Drive Nov 27 22:43:01 volumio wireless.js[811]: trying... Nov 27 22:43:27 volumio systemd[1]: Time has been changed Nov 27 22:43:27 volumio sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 27 22:43:27 volumio sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 27 22:43:27 volumio sudo[947]: pam_unix(sudo:session): session closed for user root Nov 27 22:43:27 volumio wpa_supplicant[943]: wlan0: Trying to associate with a4:2b:8c:bb:d0:a3 (SSID='Rambuss' freq=2437 MHz) Nov 27 22:43:27 volumio wpa_supplicant[943]: wlan0: Associated with a4:2b:8c:bb:d0:a3 Nov 27 22:43:27 volumio wpa_supplicant[943]: wlan0: WPA: Key negotiation completed with a4:2b:8c:bb:d0:a3 [PTK=CCMP GTK=CCMP] Nov 27 22:43:27 volumio wpa_supplicant[943]: wlan0: CTRL-EVENT-CONNECTED - Connection to a4:2b:8c:bb:d0:a3 completed [id=0 id_str=] Nov 27 22:43:27 volumio dhcpcd[895]: wlan0: carrier acquired Nov 27 22:43:27 volumio dhcpcd[895]: wlan0: rebinding lease of 192.168.0.23 Nov 27 22:43:27 volumio dhcpcd[895]: eth0: leased 192.168.0.13 for 86400 seconds Nov 27 22:43:27 volumio dhcpcd[510]: eth0: removing route to 192.168.0.0/24 Nov 27 22:43:27 volumio dhcpcd[895]: eth0: adding host route to 192.168.0.13 via 127.0.0.1 Nov 27 22:43:27 volumio dhcpcd[895]: eth0: adding route to 192.168.0.0/24 Nov 27 22:43:27 volumio dhcpcd[895]: eth0: adding default route via 192.168.0.1 Nov 27 22:43:27 volumio dhcpcd[510]: eth0: removing default route via 192.168.0.1 Nov 27 22:43:27 volumio dhcpcd[986]: eth0: removing route to 192.168.0.0/24 Nov 27 22:43:27 volumio dhcpcd[986]: eth0: removing default route via 192.168.0.1 Nov 27 22:43:27 volumio dhcpcd[895]: forked to background, child pid 986 Nov 27 22:43:27 volumio sudo[890]: pam_unix(sudo:session): session closed for user root Nov 27 22:43:28 volumio smbd[915]: Starting SMB/CIFS daemon: smbd[2018/11/27 22:43:28, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 27 22:43:28 volumio smbd[915]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28, 0] ../lib/util/util.c:220(directory_create_or_exist) Nov 27 22:43:28 volumio smbd[915]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Nov 27 22:43:28 volumio smbd[915]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)

18 

Nov 27 22:43:28 volumio smbd[915]: Unable to setup corepath for smbd: No such file or directory
Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[915]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28,  0] ../source3/smbd/server.c:1241(main)
Nov 27 22:43:28 volumio smbd[915]: smbd version 4.2.14-Debian started.
Nov 27 22:43:28 volumio smbd[915]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Nov 27 22:43:28 volumio smbd[991]: [2018/11/27 22:43:28.399164,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[991]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[991]: [2018/11/27 22:43:28.401696,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[991]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[991]: [2018/11/27 22:43:28.402001,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[991]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28.399164,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[915]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28.401696,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[915]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[915]: [2018/11/27 22:43:28.402001,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[915]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:28 volumio smbd[915]: .
Nov 27 22:43:28 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
Nov 27 22:43:28 volumio wireless.js[811]: trying...
Nov 27 22:43:28 volumio sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Nov 27 22:43:28 volumio winbindd[998]: [2018/11/27 22:43:28.491996,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio winbindd[998]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory
Nov 27 22:43:28 volumio sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 27 22:43:28 volumio sudo[994]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:28 volumio winbindd[1005]: [2018/11/27 22:43:28.588985,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio winbindd[1005]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory
Nov 27 22:43:28 volumio smbd[992]: [2018/11/27 22:43:28.645601,  0] ../lib/util/become_daemon.c:124(daemon_ready)
Nov 27 22:43:28 volumio smbd[992]: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Nov 27 22:43:28 volumio smbd[1006]: [2018/11/27 22:43:28.655621,  0] ../lib/util/debug.c:603(reopen_logs_internal)
Nov 27 22:43:28 volumio smbd[1006]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Nov 27 22:43:29 volumio wireless.js[811]: trying...
Nov 27 22:43:29 volumio sudo[1009]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Nov 27 22:43:29 volumio sudo[1009]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 27 22:43:29 volumio sudo[1009]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:29 volumio dhcpcd[986]: wlan0: leased 192.168.0.23 for 86400 seconds
Nov 27 22:43:29 volumio avahi-daemon[713]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.23.
Nov 27 22:43:29 volumio avahi-daemon[713]: New relevant interface wlan0.IPv4 for mDNS.
Nov 27 22:43:29 volumio dhcpcd[986]: eth0: adding route to 192.168.0.0/24
Nov 27 22:43:29 volumio avahi-daemon[713]: Registering new address record for 192.168.0.23 on wlan0.IPv4.
Nov 27 22:43:29 volumio dhcpcd[986]: eth0: adding default route via 192.168.0.1
Nov 27 22:43:29 volumio dhcpcd[986]: wlan0: adding host route to 192.168.0.23 via 127.0.0.1
Nov 27 22:43:29 volumio dhcpcd[986]: wlan0: adding route to 192.168.0.0/24
Nov 27 22:43:29 volumio dhcpcd[986]: wlan0: adding default route via 192.168.0.1
Nov 27 22:43:29 volumio dhcpcd[986]: eth0: removing route to 192.168.0.0/24
Nov 27 22:43:29 volumio dhcpcd[986]: eth0: removing default route via 192.168.0.1
Nov 27 22:43:30 volumio wireless.js[811]: trying...
Nov 27 22:43:30 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Nov 27 22:43:30 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 27 22:43:30 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:30 volumio wireless.js[811]: Connected to: ----Rambuss
Nov 27 22:43:30 volumio wireless.js[811]: ----
Nov 27 22:43:30 volumio wireless.js[811]: ... joined AP, wlan0 IPv4 is 192.168.0.23, ipV6 is undefined
Nov 27 22:43:30 volumio wireless.js[811]: It's done! AP
Nov 27 22:43:30 volumio systemd[1]: Started Wireless Services.
Nov 27 22:43:30 volumio systemd[1]: Starting Volumio Backend Module...
Nov 27 22:43:30 volumio systemd[1]: Started Volumio Backend Module.
Nov 27 22:43:30 volumio netplugd[1059]: /etc/netplug/netplug eth0 in -> pid 1059
Nov 27 22:43:30 volumio systemd[1]: Started LSB: Brings up/down network automatically.
Nov 27 22:43:30 volumio netplugd[1060]: /etc/netplug/netplug wlan0 in -> pid 1060
Nov 27 22:43:30 volumio netplugd[1053]: eth0: state INNING pid 1059 exited status 0
Nov 27 22:43:31 volumio ntpd[761]: Listen normally on 4 wlan0 192.168.0.23 UDP 123
Nov 27 22:43:31 volumio ntpd[761]: peers refreshed
Nov 27 22:43:33 volumio volumio-remote-updater[663]: [2018-11-27 22:43:33] [info] asio async_connect error: system:111 (Connection refused)
Nov 27 22:43:33 volumio volumio-remote-updater[663]: [2018-11-27 22:43:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Nov 27 22:43:33 volumio volumio-remote-updater[663]: [2018-11-27 22:43:33] [error] handle_connect error: Underlying Transport Error
Nov 27 22:43:35 volumio volumio[1051]: info: -------------------------------------------
Nov 27 22:43:35 volumio volumio[1051]: info: -----            Volumio2              ----
Nov 27 22:43:35 volumio volumio[1051]: info: -------------------------------------------
Nov 27 22:43:35 volumio volumio[1051]: info: -----          System startup          ----
Nov 27 22:43:35 volumio volumio[1051]: info: -------------------------------------------
Nov 27 22:43:36 volumio volumio[1051]: info: Plugin folders cleanup
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning into folder /volumio/app/plugins/
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category audio_interface
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category miscellanea
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category music_service
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category plugins.json
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category system_controller
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning category user_interface
Nov 27 22:43:36 volumio volumio[1051]: info: Scanning into folder /data/plugins/
Nov 27 22:43:36 volumio volumio[1051]: info: Plugin folders cleanup completed
Nov 27 22:43:36 volumio volumio[1051]: info: Loading plugins from folder /volumio/app/plugins/
Nov 27 22:43:36 volumio volumio[1051]: info: Loading plugins from folder /data/plugins/
Nov 27 22:43:36 volumio volumio[1051]: info: Loading plugin "system"...
Nov 27 22:43:36 volumio volumio[1051]: info: Loading plugin "appearance"...
Nov 27 22:43:37 volumio volumio[1051]: info: Loading plugin "network"...
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "services"...
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "alsa_controller"...
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "wizard"...
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "volumio_command_line_client"...
Nov 27 22:43:38 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:38 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:38 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:38 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "upnp"...
Nov 27 22:43:38 volumio volumio[1051]: info: [1543358618364] Starting Upmpd Daemon
Nov 27 22:43:38 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "mpd"...
Nov 27 22:43:38 volumio volumio[1051]: info: Loading plugin "upnp_browser"...
Nov 27 22:43:39 volumio kernel: Voltage normalised (0x00000000)
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "networkfs"...
Nov 27 22:43:39 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "alarm-clock"...
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "airplay_emulation"...
Nov 27 22:43:39 volumio volumio[1051]: info: [1543358619884] Starting Shairport Sync
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "last_100"...
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "webradio"...
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "i2s_dacs"...
Nov 27 22:43:39 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 27 22:43:39 volumio volumio[1051]: info: Loading plugin "volumiodiscovery"...
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node>
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 27 22:43:40 volumio node[1051]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister>
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node>
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibili
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** Please fix your application to use the native API of Avahi!
Nov 27 22:43:40 volumio volumio[1051]: *** WARNING *** For more information see <http://0pointer.de/avahi-compat?s=libdns_sd&e=node&f=DNSServiceRegister>
Nov 27 22:43:40 volumio volumio[1051]: info: Applying required configuration parameters for plugin volumiodiscovery
Nov 27 22:43:40 volumio volumio[1051]: Discovery: StartAdv! undefined
Nov 27 22:43:40 volumio volumio[1051]: Discovery: Started advertising... Volumio - undefined
Nov 27 22:43:40 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Nov 27 22:43:40 volumio volumio[1051]: info: Loading plugin "albumart"...
Nov 27 22:43:40 volumio volumio[1051]: info: Plugin example_plugin is not enabled
Nov 27 22:43:40 volumio volumio[1051]: info: Loading plugin "updater_comm"...
Nov 27 22:43:41 volumio volumio[1051]: info: Plugin mpdemulation is not enabled
Nov 27 22:43:41 volumio volumio[1051]: info: Loading plugin "rest_api"...
Nov 27 22:43:41 volumio volumio[1051]: info: Loading plugin "websocket"...
Nov 27 22:43:41 volumio volumio[1051]: info: ___________ START PLUGINS ___________
Nov 27 22:43:41 volumio volumio[1051]: info: PLUGIN START: appearance
Nov 27 22:43:41 volumio volumio[1051]: info: PLUGIN START: wizard
Nov 27 22:43:41 volumio volumio[1051]: info: PLUGIN START: upnp_browser
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 27 22:43:41 volumio volumio[1051]: info: [1543358621259] CoreMusicLibrary::Adding element Media Servers
Nov 27 22:43:41 volumio volumio[1051]: info: PLUGIN START: last_100
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 27 22:43:41 volumio volumio[1051]: info: [1543358621310] CoreMusicLibrary::Adding element Last_100
Nov 27 22:43:41 volumio volumio[1051]: info: PLUGIN START: webradio
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Nov 27 22:43:41 volumio volumio[1051]: info: [1543358621325] CoreMusicLibrary::Adding element Webradio
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Nov 27 22:43:41 volumio volumio[1051]: info: Loading i18n strings for locale en
Nov 27 22:43:41 volumio kernel: Under-voltage detected! (0x00050005)
Nov 27 22:43:41 volumio volumio[1051]: Updating browse sources language
Nov 27 22:43:41 volumio volumio[1051]: Cannot find translation for sourceMedia Servers
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::initPlayerControls
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: BOOT COMPLETED
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Nov 27 22:43:41 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent
Nov 27 22:43:41 volumio volumio[1051]: Express server listening on port 3000
Nov 27 22:43:41 volumio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Nov 27 22:43:41 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Nov 27 22:43:41 volumio volumio[1051]: Volumio Calling Home
Nov 27 22:43:42 volumio volumio[1051]: info: CoreStateMachine::resetVolumioState
Nov 27 22:43:42 volumio volumio[1051]: info: CoreStateMachine::getcurrentVolume
Nov 27 22:43:42 volumio volumio[1051]: info: CoreCommandRouter::volumioRetrievevolume
Nov 27 22:43:42 volumio volumio[1051]: info: CoreStateMachine::updateTrackBlock
Nov 27 22:43:42 volumio volumio[1051]: info: CorePlayQueue::getTrackBlock
Nov 27 22:43:42 volumio volumio[1051]: info: Setting Device type: Raspberry PI
Nov 27 22:43:42 volumio volumio[1051]: info: MPD running with PID714 ,establishing connection
Nov 27 22:43:43 volumio volumio[1051]: info: VolumeController:: Volume=29 Mute =false
Nov 27 22:43:43 volumio volumio[1051]: info: CoreStateMachine::pushState
Nov 27 22:43:43 volumio volumio[1051]: info: CoreStateMachine::getState
Nov 27 22:43:43 volumio volumio[1051]: info: CorePlayQueue::getTrack 0
Nov 27 22:43:43 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState
Nov 27 22:43:43 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 27 22:43:43 volumio volumio[1051]: info: interfaceApi::pushState
Nov 27 22:43:43 volumio volumio[1051]: info: Volumio called home
Nov 27 22:43:43 volumio volumio[1051]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Nov 27 22:43:43 volumio volumio-remote-updater[663]: [2018-11-27 22:43:43] [connect] Successful connection
Nov 27 22:43:44 volumio volumio[1051]: info: Reloading queue from file
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::setRepeat null single undefined
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::pushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::getState
Nov 27 22:43:44 volumio volumio[1051]: info: CorePlayQueue::getTrack 0
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 27 22:43:44 volumio volumio[1051]: info: interfaceApi::pushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::setRandom null
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::pushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::getState
Nov 27 22:43:44 volumio volumio[1051]: info: CorePlayQueue::getTrack 0
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::volumioPushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Nov 27 22:43:44 volumio volumio[1051]: info: interfaceApi::pushState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::getState
Nov 27 22:43:44 volumio volumio[1051]: info: CorePlayQueue::getTrack 0
Nov 27 22:43:44 volumio volumio-remote-updater[663]: [2018-11-27 22:43:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1543
Nov 27 22:43:44 volumio volumio[1051]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Nov 27 22:43:44 volumio volumio[1051]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false}
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Nov 27 22:43:44 volumio volumio[1051]: Plugin multiroom or method getMultiroom not found
Nov 27 22:43:44 volumio volumio[1051]: info: Listing playlists
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::volumioGetBrowseSources
Nov 27 22:43:44 volumio volumio[1051]: info: CoreCommandRouter::volumioGetQueue
Nov 27 22:43:44 volumio volumio[1051]: info: CoreStateMachine::getQueue
Nov 27 22:43:44 volumio volumio[1051]: info: CorePlayQueue::getQueue
Nov 27 22:43:44 volumio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Nov 27 22:43:44 volumio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 27 22:43:44 volumio volumio[1051]: Discovery: adding e7946ec6-b3ba-4bea-9895-940f2af81de0
Nov 27 22:43:44 volumio volumio[1051]: info: mDNS: Found device Volumio
Nov 27 22:43:44 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Nov 27 22:43:44 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Nov 27 22:43:44 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:44 volumio volumio[1051]: Discovery: adding e7946ec6-b3ba-4bea-9895-940f2af81de0
Nov 27 22:43:44 volumio volumio[1051]: info: mDNS: Found device Volumio
Nov 27 22:43:44 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Nov 27 22:43:44 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Nov 27 22:43:44 volumio sudo[1121]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:44 volumio shairport-sync[1126]: Successful Startup
Nov 27 22:43:45 volumio volumio[1051]: Discovery: this is already registered,  e7946ec6-b3ba-4bea-9895-940f2af81de0
Nov 27 22:43:45 volumio volumio[1051]: info: mDNS: Found device Volumio
Nov 27 22:43:45 volumio volumio[1051]: info: [1543358625216] Shairport-Sync Started
Nov 27 22:43:45 volumio volumio[1051]: Error adding Membership: Error: addMembership EINVAL
Nov 27 22:43:45 volumio volumio[1051]: info: CoreCommandRouter::volumioGetState
Nov 27 22:43:45 volumio volumio[1051]: info: CoreStateMachine::getState
Nov 27 22:43:45 volumio volumio[1051]: info: CorePlayQueue::getTrack 0
Nov 27 22:43:47 volumio volumio[1051]: info: CoreCommandRouter::Close All Modals sent
Nov 27 22:43:48 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Nov 27 22:43:48 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Nov 27 22:43:48 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0)
Nov 27 22:43:48 volumio systemd[1]: Starting UPnP Renderer front-end to MPD...
Nov 27 22:43:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Nov 27 22:43:48 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root
Nov 27 22:43:48 volumio volumio[1051]: info: Upmpdcli Daemon Started
Nov 27 22:43:49 volumio kernel: Voltage normalised (0x00000000)
Nov 27 22:43:56 volumio udisks-glue[661]: error: Timeout
Nov 27 22:44:17 volumio sshd[1153]: Accepted password for volumio from 192.168.0.19 port 59227 ssh2
Nov 27 22:44:17 volumio sshd[1153]: pam_unix(sshd:session): session opened for user volumio by (uid=0)
Nov 27 22:44:17 volumio systemd[1]: Starting user-1000.slice.
Nov 27 22:44:17 volumio systemd[1]: Created slice user-1000.slice.
Nov 27 22:44:17 volumio systemd[1]: Starting User Manager for UID 1000...
Nov 27 22:44:17 volumio systemd[1]: Starting Session c1 of user volumio.
Nov 27 22:44:17 volumio systemd-logind[670]: New session c1 of user volumio.
Nov 27 22:44:17 volumio systemd[1]: Started Session c1 of user volumio.
Nov 27 22:44:17 volumio systemd[1158]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0)
Nov 27 22:44:17 volumio systemd[1158]: Starting Paths.
Nov 27 22:44:17 volumio systemd[1158]: Reached target Paths.
Nov 27 22:44:17 volumio systemd[1158]: Starting Timers.
Nov 27 22:44:17 volumio systemd[1158]: Reached target Timers.
Nov 27 22:44:17 volumio systemd[1158]: Starting Sockets.
Nov 27 22:44:17 volumio systemd[1158]: Reached target Sockets.
Nov 27 22:44:17 volumio systemd[1158]: Starting Basic System.
Nov 27 22:44:17 volumio systemd[1158]: Reached target Basic System.
Nov 27 22:44:17 volumio systemd[1158]: Starting Default.
Nov 27 22:44:17 volumio systemd[1158]: Reached target Default.
Nov 27 22:44:17 volumio systemd[1158]: Startup finished in 36ms.
Nov 27 22:44:17 volumio systemd[1]: Started User Manager for UID 1000.
Nov 27 22:44:17 volumio sshd[1153]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Nov 27 22:44:17 volumio sshd[1153]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Nov 27 22:44:49 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 27 22:46:19 volumio systemd[1]: mpd.service stop-final-sigterm timed out. Killing.
Nov 27 22:46:20 volumio systemd[1]: mpd.service: main process exited, code=killed, status=9/KILL
Nov 27 22:46:20 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 27 22:46:20 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 27 22:46:20 volumio systemd[1]: Starting Music Player Daemon...
Nov 27 22:47:50 volumio systemd[1]: mpd.service start operation timed out. Terminating.
Nov 27 22:48:07 volumio volumio[1051]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Nov 27 22:48:07 volumio volumio[1051]: info: CURURI: music-library
Nov 27 22:48:45 volumio sudo[1193]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl
Nov 27 22:48:45 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Nov 27 22:49:06 volumio volumio[1051]: Upnp client error: Error: This socket has been ended by the other party
Nov 27 22:49:07 volumio systemd[1]: Failed to start Music Player Daemon.
Nov 27 22:49:07 volumio systemd[1]: Unit mpd.service entered failed state.
Nov 27 22:49:07 volumio systemd[1]: Starting Music Player Daemon...
Nov 27 22:49:28 volumio kernel: Under-voltage detected! (0x00050005)
lines 920-976/976 (END)
19

So from the logs it shows that your hdd is recognised.
It pops up under ‘lsusb’:

Bus 001 Device 004: ID 0bc2:3322 Seagate RSS LLC

And is recognised:

Nov 27 22:42:46 volumio kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Nov 27 22:42:46 volumio kernel: usb 1-1.3: New USB device found, idVendor=0bc2, idProduct=3322 Nov 27 22:42:46 volumio kernel: usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Nov 27 22:42:46 volumio kernel: usb 1-1.3: Product: Expansion Desk Nov 27 22:42:46 volumio kernel: usb 1-1.3: Manufacturer: Seagate Nov 27 22:42:46 volumio kernel: usb 1-1.3: SerialNumber: NA8FHGJJ

Yet, we’re unsure if it is mounted or that the problem is somewhere else. At least your MPD error still pop up, and I see quite some logging errors for Samba.
Besides that, some other noteworthy items in the logs:

Nov 27 22:42:50 volumio kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16).

Nov 27 22:42:56 volumio udisks-glue[661]: Device file /dev/sda2 inserted Nov 27 22:42:56 volumio udisks-glue[661]: Trying to automount /dev/sda2... Nov 27 22:42:56 volumio volumio[662]: Could not open config: /tmp/upmpdcli.conf

Few questions:

  1. What is the make, model and size of the SD card you use?
  2. Did you leave the Pi to boot for long enough (a least 10 min.) on first boot before configuring anything?
  3. Did you hotplug the external hdd?
  4. What is the file format of the external hdd?

By the way, it’s much easier to send a full log via the built-in dev tools. Go to your volumio/dev, send the log and post the link here. That way we can see the entire log as one without cluttering the topic here. :wink:

20

the problem is unlikely related to your SD card type or brand or volumio uptime.
It could simply be the size of your disk causing it not to mount properly.
If it is > 2.2TB you might be hitting a limit, depending on how and with what filesystem your disk has been formatted.
Is it a 3 TB disk or even bigger?