Tue Oct 30 20:54:33 UTC 2018 Description="'Boot log of DAC not recognised despite external power'" # cat /proc/version --------------- Linux version 4.14.42-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1114 SMP Mon May 21 16:39:21 BST 2018 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="d0d14b37ffe8e45861e4e0b9816241fbafb4eb91" VOLUMIO_FE_VERSION="81c7100e9c7342b16fc9e0ca5908a26771e08c4f" VOLUMIO_BE_VERSION="645f0e55945bdd625a662a1ee21ba0dfb11bd73e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Jun 21 01:30:10 CEST 2018" VOLUMIO_VERSION="2.413" VOLUMIO_HARDWARE="pi" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr b8:27:eb:9f:c4:46 UP BROADCAST MULTICAST MTU:1500 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:8142 errors:0 dropped:0 overruns:0 frame:0 TX packets:8142 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:749836 (732.2 KiB) TX bytes:749836 (732.2 KiB) wlan0 Link encap:Ethernet HWaddr b8:27:eb:ca:91:13 inet addr:192.168.1.101 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:1679 errors:0 dropped:0 overruns:0 frame:0 TX packets:2280 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:173881 (169.8 KiB) TX bytes:2454941 (2.3 MiB) # iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:"BayerischerHimmel" Mode:Managed Frequency:2.432 GHz Access Point: 1C:67:58:8A:5E:20 Bit Rate=57.7 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Power Management:off Link Quality=62/70 Signal level=-48 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:7 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 7/7 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 ALSA [bcm2835 IEC958/HDMI] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 0 20:32 ? 00:00:04 /sbin/init root 2 0 0 20:32 ? 00:00:00 [kthreadd] root 4 2 0 20:32 ? 00:00:00 [kworker/0:0H] root 5 2 0 20:32 ? 00:00:00 [kworker/u8:0] root 6 2 0 20:32 ? 00:00:00 [mm_percpu_wq] root 7 2 0 20:32 ? 00:00:00 [ksoftirqd/0] root 8 2 0 20:32 ? 00:00:00 [rcu_sched] root 9 2 0 20:32 ? 00:00:00 [rcu_bh] root 10 2 0 20:32 ? 00:00:00 [migration/0] root 11 2 0 20:32 ? 00:00:00 [cpuhp/0] root 12 2 0 20:32 ? 00:00:00 [cpuhp/1] root 13 2 0 20:32 ? 00:00:00 [migration/1] root 14 2 0 20:32 ? 00:00:00 [ksoftirqd/1] root 16 2 0 20:32 ? 00:00:00 [kworker/1:0H] root 17 2 0 20:32 ? 00:00:00 [cpuhp/2] root 18 2 0 20:32 ? 00:00:00 [migration/2] root 19 2 0 20:32 ? 00:00:00 [ksoftirqd/2] root 21 2 0 20:32 ? 00:00:00 [kworker/2:0H] root 22 2 0 20:32 ? 00:00:00 [cpuhp/3] root 23 2 0 20:32 ? 00:00:00 [migration/3] root 24 2 0 20:32 ? 00:00:00 [ksoftirqd/3] root 26 2 0 20:32 ? 00:00:00 [kworker/3:0H] root 27 2 0 20:32 ? 00:00:00 [kdevtmpfs] root 28 2 0 20:32 ? 00:00:00 [netns] root 30 2 0 20:32 ? 00:00:00 [kworker/1:1] root 31 2 0 20:32 ? 00:00:00 [kworker/2:1] root 33 2 0 20:32 ? 00:00:00 [khungtaskd] root 34 2 0 20:32 ? 00:00:00 [oom_reaper] root 35 2 0 20:32 ? 00:00:00 [writeback] root 36 2 0 20:32 ? 00:00:00 [kcompactd0] root 37 2 0 20:32 ? 00:00:00 [crypto] root 38 2 0 20:32 ? 00:00:00 [kblockd] root 39 2 0 20:32 ? 00:00:00 [watchdogd] root 40 2 0 20:32 ? 00:00:00 [rpciod] root 41 2 0 20:32 ? 00:00:00 [xprtiod] root 42 2 0 20:32 ? 00:00:00 [kworker/u8:1] root 44 2 0 20:32 ? 00:00:00 [kswapd0] root 45 2 0 20:32 ? 00:00:00 [nfsiod] root 55 2 0 20:32 ? 00:00:00 [kthrotld] root 56 2 0 20:32 ? 00:00:00 [iscsi_eh] root 57 2 0 20:32 ? 00:00:00 [dwc_otg] root 58 2 0 20:32 ? 00:00:00 [DWC Notificatio] root 60 2 0 20:32 ? 00:00:00 [vchiq-slot/0] root 61 2 0 20:32 ? 00:00:00 [vchiq-recy/0] root 62 2 0 20:32 ? 00:00:00 [vchiq-sync/0] root 63 2 0 20:32 ? 00:00:00 [vchiq-keep/0] root 64 2 0 20:32 ? 00:00:00 [irq/92-mmc1] root 73 2 0 20:32 ? 00:00:00 [kworker/0:2] root 94 2 0 20:32 ? 00:00:00 [mmcqd/0] root 100 2 0 20:32 ? 00:00:00 [jbd2/mmcblk0p2-] root 101 2 0 20:32 ? 00:00:00 [ext4-rsv-conver] root 108 2 0 20:32 ? 00:00:00 [loop0] root 111 2 0 20:32 ? 00:00:00 [kworker/1:1H] root 115 2 0 20:32 ? 00:00:00 [kworker/0:1H] root 129 2 0 20:32 ? 00:00:00 [jbd2/mmcblk0p3-] root 130 2 0 20:32 ? 00:00:00 [ext4-rsv-conver] root 137 2 0 20:32 ? 00:00:00 [ipv6_addrconf] root 143 2 0 20:32 ? 00:00:00 [kworker/3:1H] root 144 2 0 20:32 ? 00:00:00 [kworker/2:1H] root 163 2 0 20:32 ? 00:00:00 [kworker/2:2] root 170 1 0 20:32 ? 00:00:00 /lib/systemd/systemd-journald root 183 2 0 20:32 ? 00:00:00 [kworker/1:2] root 190 1 0 20:32 ? 00:00:00 /lib/systemd/systemd-udevd root 293 2 0 20:32 ? 00:00:00 [cfg80211] root 302 2 0 20:32 ? 00:00:00 [brcmf_wq/mmc1:0] root 303 2 0 20:32 ? 00:00:00 [brcmf_wdog/mmc1] root 492 1 0 20:32 ? 00:00:00 /sbin/rpcbind -w statd 510 1 0 20:32 ? 00:00:00 /sbin/rpc.statd root 522 1 0 20:32 ? 00:00:00 /usr/sbin/rpc.idmapd root 524 1 0 20:32 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 527 1 0 20:32 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 529 1 0 20:32 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 535 1 0 20:32 ? 00:00:00 /lib/systemd/systemd-logind avahi 558 1 0 20:32 ? 00:00:00 avahi-daemon: running [picassette.local] mpd 559 1 0 20:32 ? 00:00:01 /usr/bin/mpd --no-daemon message+ 560 1 0 20:32 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 601 1 0 20:32 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 621 1 0 20:32 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:103 avahi 626 558 0 20:32 ? 00:00:00 avahi-daemon: chroot helper root 629 1 0 20:32 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 640 1 0 20:32 ? 00:00:00 /usr/sbin/sshd -D root 646 629 0 20:32 ? 00:00:00 udisks-daemon: not polling any devices root 647 1 0 20:32 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 665 1 0 20:32 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 716 1 0 20:32 ? 00:00:00 /usr/sbin/nmbd -D root 717 716 0 20:32 ? 00:00:00 /usr/sbin/nmbd -D root 731 1 0 20:32 ? 00:00:00 /usr/sbin/winbindd root 732 731 0 20:32 ? 00:00:00 /usr/sbin/winbindd root 779 1 0 20:32 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 780 1 0 20:32 ? 00:00:00 /usr/sbin/smbd -D root 783 731 0 20:32 ? 00:00:00 /usr/sbin/winbindd root 784 731 0 20:32 ? 00:00:00 /usr/sbin/winbindd root 785 780 0 20:32 ? 00:00:00 /usr/sbin/smbd -D root 828 1 0 20:32 ? 00:00:00 /sbin/dhcpcd volumio 844 1 0 20:32 ? 00:00:11 /usr/local/bin/node /volumio/index.js root 878 2 0 20:33 ? 00:00:00 [cifsiod] root 879 2 0 20:33 ? 00:00:00 [cifsoplockd] root 883 2 0 20:33 ? 00:00:00 [cifsd] volumio 921 844 0 20:33 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 934 921 0 20:33 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 935 921 0 20:33 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 940 921 0 20:33 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 977 1 0 20:33 ? 00:00:00 /usr/local/bin/shairport-sync volumio 997 1 0 20:33 ? 00:00:02 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 5020 2 0 20:42 ? 00:00:00 [kworker/3:1] root 6982 2 0 20:47 ? 00:00:00 [kworker/0:0] root 7117 2 0 20:47 ? 00:00:00 [kworker/3:2] root 7387 640 0 20:48 ? 00:00:00 sshd: volumio [priv] volumio 7414 1 0 20:48 ? 00:00:00 /lib/systemd/systemd --user volumio 7417 7414 0 20:48 ? 00:00:00 (sd-pam) volumio 7420 7387 0 20:48 ? 00:00:00 sshd: volumio@pts/0 volumio 7422 7420 0 20:48 pts/0 00:00:00 -bash root 9269 2 0 20:52 ? 00:00:00 [kworker/3:0] root 9543 2 0 20:53 ? 00:00:00 [kworker/u8:2] volumio 9956 844 0 20:54 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'Boot log of DAC not recognised despite external power' volumio 9957 9956 0 20:54 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js Boot log of DAC not recognised despite external power volumio 9975 9957 0 20:54 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 9976 9975 0 20:54 ? 00:00:00 ps -ef # sudo journalctl -p 7 --------------- -- Logs begin at Tue 2018-10-30 20:24:27 UTC, end at Tue 2018-10-30 20:54:34 UTC. -- Oct 30 20:24:27 picassette systemd-journal[170]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 73.2M free of 484.2M available → current limit 30.0M). Oct 30 20:24:27 picassette kernel: Booting Linux on physical CPU 0x0 Oct 30 20:24:27 picassette kernel: Linux version 4.14.42-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1114 SMP Mon May 21 16:39:21 BST 2018 Oct 30 20:24:27 picassette kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Oct 30 20:24:27 picassette kernel: CPU: div instructions available: patching division code Oct 30 20:24:27 picassette kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Oct 30 20:24:27 picassette kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Oct 30 20:24:27 picassette kernel: Memory policy: Data cache writealloc Oct 30 20:24:27 picassette kernel: cma: Reserved 8 MiB at 0x3dc00000 Oct 30 20:24:27 picassette kernel: On node 0 totalpages: 255488 Oct 30 20:24:27 picassette kernel: free_area_init_node: node 0, pgdat 80c84fc0, node_mem_map bd336000 Oct 30 20:24:27 picassette kernel: Normal zone: 2246 pages used for memmap Oct 30 20:24:27 picassette kernel: Normal zone: 0 pages reserved Oct 30 20:24:27 picassette kernel: Normal zone: 255488 pages, LIFO batch:31 Oct 30 20:24:27 picassette kernel: percpu: Embedded 17 pages/cpu @be59f000 s38720 r8192 d22720 u69632 Oct 30 20:24:27 picassette kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096 Oct 30 20:24:27 picassette kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 30 20:24:27 picassette kernel: Built 1 zonelists, mobility grouping on. Total pages: 253242 Oct 30 20:24:27 picassette 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_mem.mem_size=0x3f600000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=10 logo.nologo vt.global_cursor_default=0 loglevel=0 Oct 30 20:24:27 picassette kernel: PID hash table entries: 4096 (order: 2, 16384 bytes) Oct 30 20:24:27 picassette kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Oct 30 20:24:27 picassette kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Oct 30 20:24:27 picassette kernel: Memory: 976144K/1021952K available (7168K kernel code, 575K rwdata, 2072K rodata, 1024K init, 706K bss, 37616K reserved, 8192K cma-reserved) Oct 30 20:24:27 picassette 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 - 0x80c8feb4 ( 576 kB) .bss : 0x80c96f34 - 0x80d478b4 ( 707 kB) Oct 30 20:24:27 picassette kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 30 20:24:27 picassette kernel: ftrace: allocating 25246 entries in 75 pages Oct 30 20:24:27 picassette kernel: Hierarchical RCU implementation. Oct 30 20:24:27 picassette kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Oct 30 20:24:27 picassette kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Oct 30 20:24:27 picassette kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Oct 30 20:24:27 picassette kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Oct 30 20:24:27 picassette kernel: Switching to timer-based delay loop, resolution 52ns Oct 30 20:24:27 picassette kernel: Console: colour dummy device 80x30 Oct 30 20:24:27 picassette kernel: console [tty1] enabled Oct 30 20:24:27 picassette kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Oct 30 20:24:27 picassette kernel: pid_max: default: 32768 minimum: 301 Oct 30 20:24:27 picassette kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Oct 30 20:24:27 picassette kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Oct 30 20:24:27 picassette kernel: Disabling memory control group subsystem Oct 30 20:24:27 picassette kernel: CPU: Testing write buffer coherency: ok Oct 30 20:24:27 picassette kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Oct 30 20:24:27 picassette kernel: Setting up static identity map for 0x100000 - 0x10003c Oct 30 20:24:27 picassette kernel: Hierarchical SRCU implementation. Oct 30 20:24:27 picassette kernel: smp: Bringing up secondary CPUs ... Oct 30 20:24:27 picassette kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Oct 30 20:24:27 picassette kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Oct 30 20:24:27 picassette kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Oct 30 20:24:27 picassette kernel: smp: Brought up 1 node, 4 CPUs Oct 30 20:24:27 picassette kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Oct 30 20:24:27 picassette kernel: CPU: All CPU(s) started in HYP mode. Oct 30 20:24:27 picassette kernel: CPU: Virtualization extensions available. Oct 30 20:24:27 picassette kernel: devtmpfs: initialized Oct 30 20:24:27 picassette kernel: random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0 Oct 30 20:24:27 picassette kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Oct 30 20:24:27 picassette kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Oct 30 20:24:27 picassette kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Oct 30 20:24:27 picassette kernel: pinctrl core: initialized pinctrl subsystem Oct 30 20:24:27 picassette kernel: NET: Registered protocol family 16 Oct 30 20:24:27 picassette kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Oct 30 20:24:27 picassette kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Oct 30 20:24:27 picassette kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Oct 30 20:24:27 picassette kernel: Serial: AMBA PL011 UART driver Oct 30 20:24:27 picassette kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Oct 30 20:24:27 picassette kernel: uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe Oct 30 20:24:27 picassette kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at be813000, dmachans=0x1 Oct 30 20:24:27 picassette kernel: SCSI subsystem initialized Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver usbfs Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver hub Oct 30 20:24:27 picassette kernel: usbcore: registered new device driver usb Oct 30 20:24:27 picassette kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2018-05-18 18:06 Oct 30 20:24:27 picassette kernel: clocksource: Switched to clocksource arch_sys_counter Oct 30 20:24:27 picassette kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 20:24:27 picassette kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct 30 20:24:27 picassette kernel: FS-Cache: Loaded Oct 30 20:24:27 picassette kernel: CacheFiles: Loaded Oct 30 20:24:27 picassette kernel: NET: Registered protocol family 2 Oct 30 20:24:27 picassette kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Oct 30 20:24:27 picassette kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Oct 30 20:24:27 picassette kernel: TCP: Hash tables configured (established 8192 bind 8192) Oct 30 20:24:27 picassette kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Oct 30 20:24:27 picassette kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Oct 30 20:24:27 picassette kernel: NET: Registered protocol family 1 Oct 30 20:24:27 picassette kernel: RPC: Registered named UNIX socket transport module. Oct 30 20:24:27 picassette kernel: RPC: Registered udp transport module. Oct 30 20:24:27 picassette kernel: RPC: Registered tcp transport module. Oct 30 20:24:27 picassette kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 30 20:24:27 picassette kernel: Trying to unpack rootfs image as initramfs... Oct 30 20:24:27 picassette kernel: Freeing initrd memory: 14824K Oct 30 20:24:27 picassette kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Oct 30 20:24:27 picassette kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Oct 30 20:24:27 picassette kernel: FS-Cache: Netfs 'nfs' registered for caching Oct 30 20:24:27 picassette kernel: NFS: Registering the id_resolver key type Oct 30 20:24:27 picassette kernel: Key type id_resolver registered Oct 30 20:24:27 picassette kernel: Key type id_legacy registered Oct 30 20:24:27 picassette kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 30 20:24:27 picassette kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 30 20:24:27 picassette kernel: io scheduler noop registered (default) Oct 30 20:24:27 picassette kernel: io scheduler deadline registered Oct 30 20:24:27 picassette kernel: io scheduler cfq registered Oct 30 20:24:27 picassette kernel: io scheduler mq-deadline registered Oct 30 20:24:27 picassette kernel: io scheduler kyber registered Oct 30 20:24:27 picassette kernel: BCM2708FB: allocated DMA memory fdd10000 Oct 30 20:24:27 picassette kernel: BCM2708FB: allocated DMA channel 0 @ be813000 Oct 30 20:24:27 picassette kernel: Console: switching to colour frame buffer device 80x30 Oct 30 20:24:27 picassette kernel: bcm2835-rng 3f104000.rng: hwrng registered Oct 30 20:24:27 picassette kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB) Oct 30 20:24:27 picassette kernel: vc-sm: Videocore shared memory driver Oct 30 20:24:27 picassette kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Oct 30 20:24:27 picassette kernel: brd: module loaded Oct 30 20:24:27 picassette kernel: loop: module loaded Oct 30 20:24:27 picassette kernel: Loading iSCSI transport class v2.0-870. Oct 30 20:24:27 picassette kernel: libphy: Fixed MDIO Bus: probed Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver lan78xx Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver smsc95xx Oct 30 20:24:27 picassette kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Oct 30 20:24:27 picassette kernel: Core Release: 2.80a Oct 30 20:24:27 picassette kernel: Setting default values for core params Oct 30 20:24:27 picassette kernel: Finished setting default values for core params Oct 30 20:24:27 picassette kernel: Using Buffer DMA mode Oct 30 20:24:27 picassette kernel: Periodic Transfer Interrupt Enhancement - disabled Oct 30 20:24:27 picassette kernel: Multiprocessor Interrupt Enhancement - disabled Oct 30 20:24:27 picassette kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Oct 30 20:24:27 picassette kernel: Dedicated Tx FIFOs mode Oct 30 20:24:27 picassette kernel: WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbdd04000 dma = 0xfdd04000 len=9024 Oct 30 20:24:27 picassette kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Oct 30 20:24:27 picassette kernel: dwc_otg: Microframe scheduler enabled Oct 30 20:24:27 picassette kernel: WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805e7624 Oct 30 20:24:27 picassette kernel: WARN::hcd_init_fiq:460: FIQ ASM at 0x805e798c length 36 Oct 30 20:24:27 picassette kernel: WARN::hcd_init_fiq:486: MPHI regs_base at 0xbe87e000 Oct 30 20:24:27 picassette kernel: dwc_otg 3f980000.usb: DWC OTG Controller Oct 30 20:24:27 picassette kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Oct 30 20:24:27 picassette kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000 Oct 30 20:24:27 picassette kernel: Init: Port Power? op_state=1 Oct 30 20:24:27 picassette kernel: Init: Power Port (0) Oct 30 20:24:27 picassette kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Oct 30 20:24:27 picassette kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 30 20:24:27 picassette kernel: usb usb1: Product: DWC OTG Controller Oct 30 20:24:27 picassette kernel: usb usb1: Manufacturer: Linux 4.14.42-v7+ dwc_otg_hcd Oct 30 20:24:27 picassette kernel: usb usb1: SerialNumber: 3f980000.usb Oct 30 20:24:27 picassette kernel: hub 1-0:1.0: USB hub found Oct 30 20:24:27 picassette kernel: hub 1-0:1.0: 1 port detected Oct 30 20:24:27 picassette kernel: dwc_otg: FIQ enabled Oct 30 20:24:27 picassette kernel: dwc_otg: NAK holdoff enabled Oct 30 20:24:27 picassette kernel: dwc_otg: FIQ split-transaction FSM enabled Oct 30 20:24:27 picassette kernel: Module dwc_common_port init Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver usb-storage Oct 30 20:24:27 picassette kernel: mousedev: PS/2 mouse device common for all mice Oct 30 20:24:27 picassette kernel: IR NEC protocol handler initialized Oct 30 20:24:27 picassette kernel: IR RC5(x/sz) protocol handler initialized Oct 30 20:24:27 picassette kernel: IR RC6 protocol handler initialized Oct 30 20:24:27 picassette kernel: IR JVC protocol handler initialized Oct 30 20:24:27 picassette kernel: IR Sony protocol handler initialized Oct 30 20:24:27 picassette kernel: IR SANYO protocol handler initialized Oct 30 20:24:27 picassette kernel: IR Sharp protocol handler initialized Oct 30 20:24:27 picassette kernel: IR MCE Keyboard/mouse protocol handler initialized Oct 30 20:24:27 picassette kernel: IR XMP protocol handler initialized Oct 30 20:24:27 picassette kernel: bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer Oct 30 20:24:27 picassette kernel: bcm2835-cpufreq: min=600000 max=1200000 Oct 30 20:24:27 picassette kernel: sdhci: Secure Digital Host Controller Interface driver Oct 30 20:24:27 picassette kernel: sdhci: Copyright(c) Pierre Ossman Oct 30 20:24:27 picassette kernel: mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe Oct 30 20:24:27 picassette kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Oct 30 20:24:27 picassette kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 30 20:24:27 picassette kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 30 20:24:27 picassette kernel: hidraw: raw HID events driver (C) Jiri Kosina Oct 30 20:24:27 picassette kernel: usbcore: registered new interface driver usbhid Oct 30 20:24:27 picassette kernel: usbhid: USB HID core driver Oct 30 20:24:27 picassette kernel: vchiq: vchiq_init_state: slot_zero = bdd80000, is_master = 0 Oct 30 20:24:27 picassette kernel: [vc_sm_connected_init]: start Oct 30 20:24:27 picassette kernel: vc_vchi_sm_init: failed to open VCHI service (-1) Oct 30 20:24:27 picassette kernel: [vc_sm_connected_init]: failed to initialize shared memory service Oct 30 20:24:27 picassette kernel: [vc_sm_connected_init]: end - returning -1 Oct 30 20:24:27 picassette kernel: Initializing XFRM netlink socket Oct 30 20:24:27 picassette kernel: NET: Registered protocol family 17 Oct 30 20:24:27 picassette kernel: Key type dns_resolver registered Oct 30 20:24:27 picassette kernel: Registering SWP/SWPB emulation handler Oct 30 20:24:27 picassette kernel: registered taskstats version 1 Oct 30 20:24:27 picassette kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Oct 30 20:24:27 picassette kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 Oct 30 20:24:27 picassette kernel: mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 Oct 30 20:24:27 picassette kernel: mmc-bcm2835 3f300000.mmc: DMA channel allocated Oct 30 20:24:27 picassette kernel: sdhost: log_buf @ bdd07000 (fdd07000) Oct 30 20:24:27 picassette kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Oct 30 20:24:27 picassette kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 20:24:27 picassette kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Oct 30 20:24:27 picassette kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Oct 30 20:24:27 picassette kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Oct 30 20:24:27 picassette kernel: of_cfs_init Oct 30 20:24:27 picassette kernel: of_cfs_init: OK Oct 30 20:24:27 picassette kernel: Freeing unused kernel memory: 1024K Oct 30 20:24:27 picassette kernel: random: fast init done Oct 30 20:24:27 picassette kernel: Indeed it is in host mode hprt0 = 00021501 Oct 30 20:24:27 picassette initramfs: Booting Volumio for BCM2835 Oct 30 20:24:27 picassette initramfs: This script mounts rootfs RO with an overlay RW layer. Oct 30 20:24:27 picassette kernel: mmc1: new high speed SDIO card at address 0001 Oct 30 20:24:27 picassette kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 30 20:24:27 picassette kernel: mmc0: host does not support reading read-only switch, assuming write-enable Oct 30 20:24:27 picassette initramfs: IMGPART=/dev/mmcblk0p2 Oct 30 20:24:27 picassette initramfs: IMGFILE=/volumio_current.sqsh Oct 30 20:24:27 picassette initramfs: Boot delay (except first time) will be 10 seconds Oct 30 20:24:27 picassette initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds Oct 30 20:24:27 picassette kernel: mmc0: new high speed SDHC card at address aaaa Oct 30 20:24:27 picassette kernel: mmcblk0: mmc0:aaaa SC16G 14.8 GiB Oct 30 20:24:27 picassette kernel: mmcblk0: p1 p2 p3 Oct 30 20:24:27 picassette kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Oct 30 20:24:27 picassette kernel: Indeed it is in host mode hprt0 = 00001101 Oct 30 20:24:27 picassette kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514 Oct 30 20:24:27 picassette kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 30 20:24:27 picassette kernel: hub 1-1:1.0: USB hub found Oct 30 20:24:27 picassette kernel: hub 1-1:1.0: 5 ports detected Oct 30 20:24:27 picassette initramfs: /dev/mmcblk0p2: Oct 30 20:24:27 picassette kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 30 20:24:27 picassette kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Oct 30 20:24:27 picassette initramfs: Doing a 10 second delay here to give kernel load a headstart Oct 30 20:24:27 picassette kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Oct 30 20:24:27 picassette kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00 Oct 30 20:24:27 picassette kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 30 20:24:27 picassette kernel: smsc95xx v1.0.6 Oct 30 20:24:27 picassette kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:9f:c4:46 Oct 30 20:24:27 picassette kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Oct 30 20:24:27 picassette kernel: usb 1-1.3: New USB device found, idVendor=2109, idProduct=2811 Oct 30 20:24:27 picassette kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 30 20:24:27 picassette kernel: usb 1-1.3: Product: USB2.0 Hub Oct 30 20:24:27 picassette kernel: usb 1-1.3: Manufacturer: VIA Labs, Inc. Oct 30 20:24:27 picassette kernel: hub 1-1.3:1.0: USB hub found Oct 30 20:24:27 picassette kernel: hub 1-1.3:1.0: 4 ports detected Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: Cannot enable. Maybe the USB cable is bad? Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: Cannot enable. Maybe the USB cable is bad? Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: attempt power cycle Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: Cannot enable. Maybe the USB cable is bad? Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: Cannot enable. Maybe the USB cable is bad? Oct 30 20:24:27 picassette kernel: usb 1-1.3-port4: unable to enumerate USB device Oct 30 20:24:27 picassette initramfs: Checking for USB updates Oct 30 20:24:27 picassette initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Oct 30 20:24:27 picassette kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 30 20:24:27 picassette kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Oct 30 20:24:27 picassette initramfs: With Option: Oct 30 20:24:27 picassette initramfs: VOLUMIO_VERSION="2.413" Oct 30 20:24:27 picassette initramfs: Finish initramfs, continue booting Volumio Oct 30 20:24:27 picassette kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 30 20:24:27 picassette systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Oct 30 20:24:27 picassette systemd[1]: Detected architecture 'arm'. Oct 30 20:24:27 picassette kernel: NET: Registered protocol family 10 Oct 30 20:24:27 picassette kernel: Segment Routing with IPv6 Oct 30 20:24:27 picassette systemd[1]: Inserted module 'ipv6' Oct 30 20:24:27 picassette systemd[1]: Set hostname to . Oct 30 20:24:27 picassette kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Oct 30 20:24:27 picassette kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 30 20:24:27 picassette systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Oct 30 20:24:27 picassette systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Oct 30 20:24:27 picassette systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Oct 30 20:24:27 picassette systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Oct 30 20:24:27 picassette systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Oct 30 20:24:27 picassette systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Oct 30 20:24:27 picassette kernel: fuse init (API version 7.26) Oct 30 20:24:27 picassette systemd-journal[170]: Journal started Oct 30 20:24:27 picassette systemd[1]: Starting Slices. Oct 30 20:24:27 picassette systemd[1]: Reached target Slices. Oct 30 20:24:27 picassette systemd[1]: Starting Remount Root and Kernel File Systems... Oct 30 20:24:27 picassette systemd[1]: Started Restore / save the current clock. Oct 30 20:24:27 picassette systemd[1]: Started Create list of required static device nodes for the current kernel. Oct 30 20:24:27 picassette systemd[1]: Time has been changed Oct 30 20:24:27 picassette systemd[1]: Starting Create Static Device Nodes in /dev... Oct 30 20:24:27 picassette systemd[1]: Mounted Debug File System. Oct 30 20:24:27 picassette systemd[1]: Mounted POSIX Message Queue File System. Oct 30 20:24:27 picassette systemd[1]: Started Remount Root and Kernel File Systems. Oct 30 20:24:27 picassette systemd[1]: Started Various fixups to make systemd work better on Debian. Oct 30 20:24:27 picassette systemd-modules-load[168]: Inserted module 'fuse' Oct 30 20:24:27 picassette fake-hwclock[156]: Tue Oct 30 20:24:27 UTC 2018 Oct 30 20:24:27 picassette systemd[1]: Starting udev Kernel Device Manager... Oct 30 20:24:27 picassette systemd[1]: Starting Local File Systems (Pre). Oct 30 20:24:27 picassette systemd[1]: Reached target Local File Systems (Pre). Oct 30 20:24:27 picassette systemd[1]: Mounting /tmp... Oct 30 20:24:27 picassette systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 30 20:24:27 picassette systemd-modules-load[168]: Inserted module 'i2c_dev' Oct 30 20:24:27 picassette systemd[1]: Mounting /var/spool/cups... Oct 30 20:24:27 picassette kernel: i2c /dev entries driver Oct 30 20:24:27 picassette systemd[1]: Mounting /var/log... Oct 30 20:24:27 picassette systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 30 20:24:27 picassette systemd[1]: Mounted /tmp. Oct 30 20:24:27 picassette systemd[1]: Started Load Kernel Modules. Oct 30 20:24:27 picassette systemd[1]: Mounted /var/log. Oct 30 20:24:27 picassette systemd[1]: Mounted /var/spool/cups. Oct 30 20:24:27 picassette systemd[1]: Started udev Kernel Device Manager. Oct 30 20:24:27 picassette systemd-udevd[190]: starting version 215 Oct 30 20:24:27 picassette systemd[1]: Starting Show Plymouth Boot Screen... Oct 30 20:24:27 picassette systemd[1]: Starting Copy rules generated while the root was ro... Oct 30 20:24:27 picassette systemd[1]: Mounting /var/spool/cups/tmp... Oct 30 20:24:27 picassette systemd[1]: Mounting FUSE Control File System... Oct 30 20:24:27 picassette systemd[1]: Mounting Configuration File System... Oct 30 20:24:27 picassette systemd[1]: Starting Apply Kernel Variables... Oct 30 20:24:27 picassette systemd[1]: Mounted FUSE Control File System. Oct 30 20:24:27 picassette systemd[1]: Mounted /var/spool/cups/tmp. Oct 30 20:24:27 picassette systemd[1]: Started Copy rules generated while the root was ro. Oct 30 20:24:27 picassette systemd[1]: Mounted Configuration File System. Oct 30 20:24:27 picassette systemd[1]: Started Apply Kernel Variables. Oct 30 20:24:27 picassette systemd[1]: Started Show Plymouth Boot Screen. Oct 30 20:24:27 picassette systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Oct 30 20:24:27 picassette systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 30 20:24:27 picassette systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 30 20:24:27 picassette systemd[1]: Starting Paths. Oct 30 20:24:27 picassette systemd[1]: Reached target Paths. Oct 30 20:24:28 picassette kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 30 20:24:28 picassette kernel: snd_bcm2835: unknown parameter 'index' ignored Oct 30 20:24:28 picassette kernel: bcm2835_alsa bcm2835_alsa: card created with 8 channels Oct 30 20:24:28 picassette systemd[1]: Starting Sound Card. Oct 30 20:24:28 picassette systemd[1]: Reached target Sound Card. Oct 30 20:24:28 picassette systemd[1]: Found device /dev/mmcblk0p1. Oct 30 20:24:28 picassette systemd[1]: Starting File System Check on /dev/mmcblk0p1... Oct 30 20:24:28 picassette kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Oct 30 20:24:28 picassette systemd[1]: Starting system-ifup.slice. Oct 30 20:24:28 picassette systemd[1]: Created slice system-ifup.slice. Oct 30 20:24:28 picassette kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001 Oct 30 20:24:28 picassette kernel: usbcore: registered new interface driver brcmfmac Oct 30 20:24:28 picassette systemd-fsck[297]: fsck.fat 3.0.27 (2014-11-12) Oct 30 20:24:28 picassette systemd-fsck[297]: /dev/mmcblk0p1: 173 files, 73937/123044 clusters Oct 30 20:24:28 picassette systemd[1]: Started File System Check on /dev/mmcblk0p1. Oct 30 20:24:28 picassette systemd[1]: Mounting /boot... Oct 30 20:24:28 picassette systemd[1]: Mounted /boot. Oct 30 20:24:28 picassette systemd[1]: Starting Local File Systems. Oct 30 20:24:28 picassette systemd[1]: Reached target Local File Systems. Oct 30 20:24:28 picassette systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 30 20:24:28 picassette systemd[1]: Starting Remote File Systems. Oct 30 20:24:28 picassette systemd[1]: Reached target Remote File Systems. Oct 30 20:24:28 picassette systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Oct 30 20:24:28 picassette systemd[1]: Starting Create Volatile Files and Directories... Oct 30 20:24:28 picassette systemd[1]: Starting LSB: Raise network interfaces.... Oct 30 20:24:28 picassette systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Oct 30 20:24:28 picassette systemd[1]: Started Create Volatile Files and Directories. Oct 30 20:24:28 picassette systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 30 20:24:28 picassette 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 Oct 30 20:24:28 picassette 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 Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-10-23 03:47:14 Oct 30 20:24:28 picassette systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 30 20:24:28 picassette systemd[1]: Starting system-systemd\x2drfkill.slice. Oct 30 20:24:28 picassette systemd[1]: Created slice system-systemd\x2drfkill.slice. Oct 30 20:24:28 picassette systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Oct 30 20:24:28 picassette systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Oct 30 20:24:29 picassette systemd[1]: Received SIGRTMIN+20 from PID 208 (plymouthd). Oct 30 20:24:29 picassette systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 30 20:24:29 picassette kernel: brcmfmac: power management disabled Oct 30 20:24:30 picassette kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 30 20:24:30 picassette kernel: brcmfmac: power management disabled Oct 30 20:24:30 picassette networking[313]: Configuring network interfaces...done. Oct 30 20:24:30 picassette systemd[1]: Started LSB: Raise network interfaces.. Oct 30 20:24:30 picassette systemd[1]: Starting ifup for wlan0... Oct 30 20:24:30 picassette systemd[1]: Started ifup for wlan0. Oct 30 20:24:30 picassette systemd[1]: Starting ifup for eth0... Oct 30 20:24:30 picassette systemd[1]: Started ifup for eth0. Oct 30 20:24:30 picassette systemd[1]: Starting Network. Oct 30 20:24:30 picassette systemd[1]: Reached target Network. Oct 30 20:24:30 picassette systemd[1]: Starting Network is Online. Oct 30 20:24:30 picassette systemd[1]: Reached target Network is Online. Oct 30 20:24:30 picassette systemd[1]: Starting LSB: RPC portmapper replacement... Oct 30 20:24:30 picassette ifup[410]: dhcpcd[444]: version 6.0.5 starting Oct 30 20:24:30 picassette dhcpcd[444]: version 6.0.5 starting Oct 30 20:24:30 picassette dhcpcd[444]: eth0: waiting for carrier Oct 30 20:24:30 picassette ifup[410]: dhcpcd[444]: eth0: waiting for carrier Oct 30 20:24:30 picassette rpcbind[413]: Starting rpcbind daemon.... Oct 30 20:24:30 picassette systemd[1]: Started LSB: RPC portmapper replacement. Oct 30 20:24:30 picassette systemd[1]: Starting RPC Port Mapper. Oct 30 20:24:30 picassette systemd[1]: Reached target RPC Port Mapper. Oct 30 20:24:30 picassette systemd[1]: Starting LSB: NFS support files common to client and server... Oct 30 20:24:30 picassette rpc.statd[510]: Version 1.2.8 starting Oct 30 20:24:30 picassette sm-notify[511]: Version 1.2.8 starting Oct 30 20:24:30 picassette kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Oct 30 20:24:31 picassette nfs-common[504]: Starting NFS common utilities: statd idmapd. Oct 30 20:24:31 picassette systemd[1]: Started LSB: NFS support files common to client and server. Oct 30 20:24:31 picassette systemd[1]: Starting System Initialization. Oct 30 20:24:31 picassette systemd[1]: Reached target System Initialization. Oct 30 20:24:31 picassette systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Oct 30 20:24:31 picassette systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 30 20:24:31 picassette systemd[1]: Starting mpd.socket. Oct 30 20:24:31 picassette systemd[1]: Listening on mpd.socket. Oct 30 20:24:31 picassette systemd[1]: Starting D-Bus System Message Bus Socket. Oct 30 20:24:31 picassette systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 30 20:24:31 picassette systemd[1]: Starting Sockets. Oct 30 20:24:31 picassette systemd[1]: Reached target Sockets. Oct 30 20:24:31 picassette systemd[1]: Starting Daily Cleanup of Temporary Directories. Oct 30 20:24:31 picassette systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 30 20:24:31 picassette systemd[1]: Starting Timers. Oct 30 20:24:31 picassette systemd[1]: Reached target Timers. Oct 30 20:24:31 picassette systemd[1]: Started Manage Sound Card State (restore and store). Oct 30 20:24:31 picassette systemd[1]: Starting Restore Sound Card State... Oct 30 20:24:31 picassette systemd[1]: Starting Basic System. Oct 30 20:24:31 picassette systemd[1]: Reached target Basic System. Oct 30 20:24:31 picassette systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Oct 30 20:24:31 picassette systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 30 20:24:31 picassette systemd[1]: Starting Volumio Iptables Module... Oct 30 20:24:31 picassette systemd[1]: Started Volumio Iptables Module. Oct 30 20:24:31 picassette systemd[1]: Starting mount disks automatically with standby... Oct 30 20:24:31 picassette systemd[1]: Started mount disks automatically with standby. Oct 30 20:24:31 picassette systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 30 20:24:31 picassette systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 20:24:31 picassette systemd[1]: Starting volumio-remote-updater.service... Oct 30 20:24:31 picassette systemd[1]: Started volumio-remote-updater.service. Oct 30 20:24:31 picassette systemd[1]: Starting Volumio SSH enabler... Oct 30 20:24:31 picassette systemd[1]: Started Volumio SSH enabler. Oct 30 20:24:31 picassette systemd[1]: Starting Wireless Services... Oct 30 20:24:31 picassette systemd[1]: Starting OpenBSD Secure Shell server... Oct 30 20:24:31 picassette systemd[1]: Starting /etc/rc.local Compatibility... Oct 30 20:24:31 picassette systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Oct 30 20:24:31 picassette systemd[1]: Starting Login Service... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: start Winbind daemon... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: start Samba daemons for the AD DC... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: Start NTP daemon... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: Brings up/down network automatically... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Oct 30 20:24:31 picassette systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Oct 30 20:24:31 picassette systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 30 20:24:31 picassette systemd[1]: Starting Music Player Daemon... Oct 30 20:24:31 picassette systemd[1]: Starting D-Bus System Message Bus... Oct 30 20:24:31 picassette systemd[1]: Started D-Bus System Message Bus. Oct 30 20:24:31 picassette dhcpcd[557]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Oct 30 20:24:31 picassette ifplugd(eth0)[601]: ifplugd 0.28 initializing. Oct 30 20:24:31 picassette ifplugd(eth0)[601]: Using interface eth0/B8:27:EB:9F:C4:46 with driver (version: 22-Aug-2005) Oct 30 20:24:31 picassette ifplugd(eth0)[601]: Using detection mode: SIOCETHTOOL Oct 30 20:24:31 picassette ifplugd(eth0)[601]: Initialization complete, link beat not detected. Oct 30 20:24:31 picassette dhcpcd[557]: /var/run/dhcpcd-eth0.pid ... failed! Oct 30 20:24:31 picassette kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Oct 30 20:24:31 picassette loadcpufreq[547]: Loading cpufreq kernel modules...done (none). Oct 30 20:24:31 picassette ifplugd[548]: Network Interface Plugging Daemon...start eth0...done. Oct 30 20:24:31 picassette ntpd[602]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Oct 30 20:24:31 picassette ntp[540]: Starting NTP server: ntpd. Oct 30 20:24:31 picassette kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Oct 30 20:24:31 picassette ntpd[621]: proto: precision = 1.041 usec Oct 30 20:24:31 picassette ntpd[621]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Oct 30 20:24:31 picassette ntpd[621]: Listen and drop on 1 v6wildcard :: UDP 123 Oct 30 20:24:31 picassette ntpd[621]: Listen normally on 2 lo 127.0.0.1 UDP 123 Oct 30 20:24:31 picassette ntpd[621]: peers refreshed Oct 30 20:24:31 picassette ntpd[621]: Listening on routing socket on fd #19 for interface updates Oct 30 20:24:31 picassette systemd[1]: Starting Permit User Sessions... Oct 30 20:24:31 picassette systemd[1]: Started Restore Sound Card State. Oct 30 20:24:31 picassette systemd[1]: Started /etc/rc.local Compatibility. Oct 30 20:24:31 picassette systemd[1]: Started LSB: Start NTP daemon. Oct 30 20:24:31 picassette systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 30 20:24:31 picassette systemd[1]: Started LSB: Brings up/down network automatically. Oct 30 20:24:31 picassette systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Oct 30 20:24:31 picassette systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Oct 30 20:24:31 picassette systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Oct 30 20:24:31 picassette systemd[1]: Unit dhcpcd.service entered failed state. Oct 30 20:24:31 picassette avahi-daemon[558]: Found user 'avahi' (UID 104) and group 'avahi' (GID 105). Oct 30 20:24:31 picassette avahi-daemon[558]: Successfully dropped root privileges. Oct 30 20:24:31 picassette avahi-daemon[558]: avahi-daemon 0.6.31 starting up. Oct 30 20:24:31 picassette dbus[560]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Oct 30 20:24:31 picassette systemd[1]: Started Permit User Sessions. Oct 30 20:24:31 picassette avahi-daemon[558]: Successfully called chroot(). Oct 30 20:24:31 picassette avahi-daemon[558]: Successfully dropped remaining capabilities. Oct 30 20:24:32 picassette avahi-daemon[558]: Loading service file /services/volumio.service. Oct 30 20:24:32 picassette avahi-daemon[558]: Network interface enumeration completed. Oct 30 20:24:32 picassette avahi-daemon[558]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Oct 30 20:24:32 picassette avahi-daemon[558]: Server startup complete. Host name is picassette.local. Local service cookie is 1795284352. Oct 30 20:24:32 picassette avahi-daemon[558]: Service "Picassette" (/services/volumio.service) successfully established. Oct 30 20:24:32 picassette systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 30 20:24:32 picassette systemd[1]: Starting Disk Manager (legacy version)... Oct 30 20:24:32 picassette systemd-logind[535]: New seat seat0. Oct 30 20:24:32 picassette systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 30 20:24:32 picassette systemd[1]: Starting Hold until boot process finishes up... Oct 30 20:24:32 picassette systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 30 20:24:32 picassette systemd[1]: Started Login Service. Oct 30 20:24:32 picassette systemd[1]: Received SIGRTMIN+21 from PID 208 (plymouthd). Oct 30 20:24:32 picassette systemd[1]: Started OpenBSD Secure Shell server. Oct 30 20:24:32 picassette kernel: random: crng init done Oct 30 20:24:32 picassette kernel: random: 7 urandom warning(s) missed due to ratelimiting Oct 30 20:24:32 picassette systemd[1]: Started Hold until boot process finishes up. Oct 30 20:24:32 picassette cpufrequtils[630]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Oct 30 20:24:32 picassette systemd[1]: Started Terminate Plymouth Boot Screen. Oct 30 20:24:32 picassette sshd[640]: Server listening on 0.0.0.0 port 22. Oct 30 20:24:32 picassette sshd[640]: Server listening on :: port 22. Oct 30 20:24:32 picassette dbus[560]: [system] Successfully activated service 'org.freedesktop.UDisks' Oct 30 20:24:32 picassette systemd[1]: Started Disk Manager (legacy version). Oct 30 20:24:32 picassette systemd[1]: Starting Getty on tty1... Oct 30 20:24:32 picassette systemd[1]: Started Getty on tty1. Oct 30 20:24:32 picassette systemd[1]: Starting Login Prompts. Oct 30 20:24:32 picassette systemd[1]: Reached target Login Prompts. Oct 30 20:24:32 picassette cpufrequtils[630]: CPU0...CPU1...CPU2...CPU3...done. Oct 30 20:24:32 picassette systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 30 20:24:32 picassette sudo[532]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Oct 30 20:24:32 picassette sudo[532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:32 picassette sudo[532]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:32 picassette dbus[560]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Oct 30 20:24:32 picassette systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Oct 30 20:24:32 picassette polkitd[665]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 30 20:24:32 picassette dbus[560]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 30 20:24:32 picassette systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Oct 30 20:24:32 picassette udisks-glue[527]: Device file /dev/mmcblk0 inserted Oct 30 20:24:32 picassette udisks-glue[527]: Device file /dev/mmcblk0p3 inserted Oct 30 20:24:33 picassette volumio-remote-updater[529]: Error: No active session Oct 30 20:24:33 picassette volumio-remote-updater[529]: [2018-10-30 20:24:33] [info] asio async_connect error: system:111 (Connection refused) Oct 30 20:24:33 picassette volumio-remote-updater[529]: [2018-10-30 20:24:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 20:24:33 picassette volumio-remote-updater[529]: [2018-10-30 20:24:33] [error] handle_connect error: Underlying Transport Error Oct 30 20:24:33 picassette haveged[524]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Oct 30 20:24:33 picassette haveged[524]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Oct 30 20:24:33 picassette haveged[524]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99786 Oct 30 20:24:33 picassette haveged[524]: haveged: fills: 0, generated: 0 Oct 30 20:24:33 picassette volumio[528]: Could not open config: /tmp/upmpdcli.conf Oct 30 20:24:33 picassette systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Oct 30 20:24:33 picassette systemd[1]: Unit upmpdcli.service entered failed state. Oct 30 20:24:33 picassette ntpd[621]: Deferring DNS for 0.debian.pool.ntp.org 1 Oct 30 20:24:33 picassette ntpd[621]: Deferring DNS for 1.debian.pool.ntp.org 1 Oct 30 20:24:33 picassette ntpd[621]: Deferring DNS for 2.debian.pool.ntp.org 1 Oct 30 20:24:33 picassette ntpd[621]: Deferring DNS for 3.debian.pool.ntp.org 1 Oct 30 20:24:33 picassette ntpd[672]: signal_no_reset: signal 17 had flags 4000000 Oct 30 20:24:35 picassette wireless.js[661]: WIRELESS DAEMON: start Oct 30 20:24:35 picassette wireless.js[661]: WIRELESS: Loaded configuration Oct 30 20:24:35 picassette wireless.js[661]: Cleaning previous... Oct 30 20:24:35 picassette systemd[1]: Stopped hotspot.service. Oct 30 20:24:35 picassette systemd[1]: Started Music Player Daemon. Oct 30 20:24:35 picassette systemd[1]: Started LSB: start Samba daemons for the AD DC. Oct 30 20:24:35 picassette sudo[691]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 30 20:24:35 picassette ntpd_intres[672]: host name not found: 0.debian.pool.ntp.org Oct 30 20:24:35 picassette ntpd_intres[672]: host name not found: 1.debian.pool.ntp.org Oct 30 20:24:35 picassette ntpd_intres[672]: host name not found: 2.debian.pool.ntp.org Oct 30 20:24:35 picassette ntpd_intres[672]: host name not found: 3.debian.pool.ntp.org Oct 30 20:24:35 picassette sudo[691]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:35 picassette sudo[691]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:35 picassette sudo[708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 30 20:24:35 picassette sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:35 picassette winbind[536]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 30 20:24:35 picassette winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 30 20:24:35 picassette winbind[536]: Unable to setup corepath for winbindd: No such file or directory Oct 30 20:24:35 picassette winbind[536]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 30 20:24:35 picassette winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 30 20:24:35 picassette winbind[536]: Unable to setup corepath for winbindd: No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../source3/winbindd/winbindd.c:1549(main) Oct 30 20:24:35 picassette winbind[536]: winbindd version 4.2.14-Debian started. Oct 30 20:24:35 picassette winbind[536]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 30 20:24:35 picassette winbindd[703]: [2018/10/30 20:24:35.722308, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbindd[703]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette winbindd[703]: [2018/10/30 20:24:35.723247, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbindd[703]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette winbind[536]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 30 20:24:35 picassette winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 30 20:24:35 picassette winbind[536]: Unable to setup corepath for winbindd: No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35.722308, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette winbind[536]: [2018/10/30 20:24:35.723247, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 30 20:24:35 picassette nmbd[541]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 30 20:24:35 picassette nmbd[541]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 30 20:24:35 picassette nmbd[541]: Unable to setup corepath for nmbd: No such file or directory Oct 30 20:24:35 picassette nmbd[715]: [2018/10/30 20:24:35.756565, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[715]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[715]: [2018/10/30 20:24:35.757906, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[715]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[716]: [2018/10/30 20:24:35.761683, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Oct 30 20:24:35 picassette nmbd[716]: started asyncdns process 717 Oct 30 20:24:35 picassette systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Oct 30 20:24:35 picassette systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Oct 30 20:24:35 picassette nmbd[541]: [2018/10/30 20:24:35, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[541]: [2018/10/30 20:24:35, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[541]: [2018/10/30 20:24:35, 0] ../source3/nmbd/nmbd.c:908(main) Oct 30 20:24:35 picassette nmbd[541]: nmbd version 4.2.14-Debian started. Oct 30 20:24:35 picassette nmbd[541]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 30 20:24:35 picassette nmbd[541]: [2018/10/30 20:24:35.756565, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[541]: [2018/10/30 20:24:35.757906, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:35 picassette nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 30 20:24:35 picassette nmbd[541]: . Oct 30 20:24:36 picassette nmbd[716]: [2018/10/30 20:24:36.120619, 0] ../lib/util/become_daemon.c:135(daemon_status) Oct 30 20:24:36 picassette nmbd[716]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 30 20:24:36 picassette sudo[708]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:36 picassette winbindd[731]: [2018/10/30 20:24:36.146789, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Oct 30 20:24:36 picassette winbindd[731]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 30 20:24:36 picassette systemd[1]: Started LSB: start Winbind daemon. Oct 30 20:24:36 picassette winbind[536]: . Oct 30 20:24:36 picassette winbindd[731]: [2018/10/30 20:24:36.189059, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 30 20:24:36 picassette winbindd[731]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 30 20:24:36 picassette winbindd[732]: [2018/10/30 20:24:36.198350, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette winbindd[732]: Unable to open new log file '/var/log/samba/log.wb-PICASSETTE': No such file or directory Oct 30 20:24:36 picassette wireless.js[661]: Stopped aP Oct 30 20:24:36 picassette wireless.js[661]: Start wireless flow Oct 30 20:24:36 picassette wireless.js[661]: Stopped hotspot (if there).. Oct 30 20:24:36 picassette sudo[736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 30 20:24:36 picassette sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:36 picassette sudo[736]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:36 picassette sudo[744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 30 20:24:36 picassette sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:36 picassette sudo[744]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:36 picassette wireless.js[661]: DHCP IP Oct 30 20:24:36 picassette wireless.js[661]: Start ap Oct 30 20:24:36 picassette sudo[753]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 30 20:24:36 picassette sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:36 picassette dhcpcd[757]: version 6.0.5 starting Oct 30 20:24:36 picassette kernel: brcmfmac: power management disabled Oct 30 20:24:36 picassette wpa_supplicant[752]: Successfully initialized wpa_supplicant Oct 30 20:24:36 picassette dhcpcd[757]: eth0: waiting for carrier Oct 30 20:24:36 picassette dhcpcd[757]: wlan0: waiting for carrier Oct 30 20:24:36 picassette smbd[718]: Starting SMB/CIFS daemon: smbd[2018/10/30 20:24:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[718]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 30 20:24:36 picassette smbd[718]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 30 20:24:36 picassette smbd[718]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 30 20:24:36 picassette smbd[718]: Unable to setup corepath for smbd: No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[718]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36, 0] ../source3/smbd/server.c:1241(main) Oct 30 20:24:36 picassette smbd[718]: smbd version 4.2.14-Debian started. Oct 30 20:24:36 picassette smbd[718]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 30 20:24:36 picassette smbd[778]: [2018/10/30 20:24:36.658513, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[778]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[778]: [2018/10/30 20:24:36.659914, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[778]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[778]: [2018/10/30 20:24:36.660062, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[778]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36.658513, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[718]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36.659914, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[718]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: [2018/10/30 20:24:36.660062, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[718]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:36 picassette smbd[718]: . Oct 30 20:24:36 picassette systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Oct 30 20:24:36 picassette winbindd[783]: [2018/10/30 20:24:36.732875, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette winbindd[783]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 30 20:24:36 picassette winbindd[784]: [2018/10/30 20:24:36.782266, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette winbindd[784]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Oct 30 20:24:36 picassette smbd[780]: [2018/10/30 20:24:36.816065, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 30 20:24:36 picassette smbd[780]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 30 20:24:36 picassette smbd[785]: [2018/10/30 20:24:36.823929, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 30 20:24:36 picassette smbd[785]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 30 20:24:37 picassette wpa_supplicant[779]: wlan0: Trying to associate with 1c:67:58:8a:5e:20 (SSID='BayerischerHimmel' freq=2432 MHz) Oct 30 20:24:37 picassette wpa_supplicant[779]: wlan0: Associated with 1c:67:58:8a:5e:20 Oct 30 20:24:37 picassette wpa_supplicant[779]: wlan0: WPA: Key negotiation completed with 1c:67:58:8a:5e:20 [PTK=CCMP GTK=CCMP] Oct 30 20:24:37 picassette wpa_supplicant[779]: wlan0: CTRL-EVENT-CONNECTED - Connection to 1c:67:58:8a:5e:20 completed [id=0 id_str=] Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: carrier acquired Oct 30 20:24:37 picassette wireless.js[661]: trying... Oct 30 20:24:37 picassette sudo[798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 20:24:37 picassette sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: rebinding lease of 192.168.1.101 Oct 30 20:24:37 picassette sudo[798]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: leased 192.168.1.101 for 3600 seconds Oct 30 20:24:37 picassette avahi-daemon[558]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.101. Oct 30 20:24:37 picassette avahi-daemon[558]: New relevant interface wlan0.IPv4 for mDNS. Oct 30 20:24:37 picassette avahi-daemon[558]: Registering new address record for 192.168.1.101 on wlan0.IPv4. Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: adding host route to 192.168.1.101 via 127.0.0.1 Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: adding route to 192.168.1.0/24 Oct 30 20:24:37 picassette dhcpcd[757]: wlan0: adding default route via 192.168.1.1 Oct 30 20:24:37 picassette dhcpcd[757]: forked to background, child pid 828 Oct 30 20:24:37 picassette sudo[753]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:38 picassette volumio-remote-updater[529]: [2018-10-30 20:24:38] [info] asio async_connect error: system:111 (Connection refused) Oct 30 20:24:38 picassette volumio-remote-updater[529]: [2018-10-30 20:24:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 30 20:24:38 picassette volumio-remote-updater[529]: [2018-10-30 20:24:38] [error] handle_connect error: Underlying Transport Error Oct 30 20:24:38 picassette wireless.js[661]: trying... Oct 30 20:24:38 picassette sudo[833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 30 20:24:38 picassette sudo[833]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:38 picassette sudo[833]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:38 picassette wireless.js[661]: Connected to: ----BayerischerHimmel Oct 30 20:24:38 picassette wireless.js[661]: ---- Oct 30 20:24:38 picassette wireless.js[661]: ... joined AP, wlan0 IPv4 is 192.168.1.101, ipV6 is undefined Oct 30 20:24:38 picassette wireless.js[661]: It's done! AP Oct 30 20:24:38 picassette systemd[1]: Started Wireless Services. Oct 30 20:24:38 picassette systemd[1]: Starting Volumio Backend Module... Oct 30 20:24:38 picassette systemd[1]: Started Volumio Backend Module. Oct 30 20:24:38 picassette systemd[1]: Starting Multi-User System. Oct 30 20:24:38 picassette systemd[1]: Reached target Multi-User System. Oct 30 20:24:38 picassette systemd[1]: Starting Graphical Interface. Oct 30 20:24:38 picassette systemd[1]: Reached target Graphical Interface. Oct 30 20:24:38 picassette systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 30 20:24:38 picassette systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 30 20:24:38 picassette systemd[1]: Startup finished in 15.386s (kernel) + 12.169s (userspace) = 27.555s. Oct 30 20:24:38 picassette ntpd[621]: Listen normally on 3 wlan0 192.168.1.101 UDP 123 Oct 30 20:24:38 picassette ntpd[621]: peers refreshed Oct 30 20:24:40 picassette ntpd_intres[672]: DNS 0.debian.pool.ntp.org -> 192.53.103.108 Oct 30 20:24:40 picassette ntpd_intres[672]: DNS 1.debian.pool.ntp.org -> 195.242.98.57 Oct 30 20:24:40 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:40 picassette volumio[844]: info: ----- Volumio2 ---- Oct 30 20:24:40 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:40 picassette volumio[844]: info: ----- System startup ---- Oct 30 20:24:40 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:40 picassette ntpd_intres[672]: DNS 2.debian.pool.ntp.org -> 5.39.184.5 Oct 30 20:24:40 picassette ntpd_intres[672]: DNS 3.debian.pool.ntp.org -> 188.42.216.204 Oct 30 20:24:41 picassette volumio[844]: info: Plugin folders cleanup Oct 30 20:24:41 picassette volumio[844]: info: Scanning into folder /volumio/app/plugins/ Oct 30 20:24:41 picassette volumio[844]: info: Scanning category audio_interface Oct 30 20:24:41 picassette volumio[844]: info: Scanning category miscellanea Oct 30 20:24:41 picassette volumio[844]: info: Scanning category music_service Oct 30 20:24:41 picassette volumio[844]: info: Scanning category plugins.json Oct 30 20:24:41 picassette volumio[844]: info: Scanning category system_controller Oct 30 20:24:41 picassette volumio[844]: info: Scanning category user_interface Oct 30 20:24:41 picassette volumio[844]: info: Scanning into folder /data/plugins/ Oct 30 20:24:41 picassette volumio[844]: info: Plugin folders cleanup completed Oct 30 20:24:41 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:41 picassette volumio[844]: info: ----- Core plugins startup ---- Oct 30 20:24:41 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:41 picassette volumio[844]: info: Loading plugins from folder /volumio/app/plugins/ Oct 30 20:24:41 picassette volumio[844]: info: Loading plugins from folder /data/plugins/ Oct 30 20:24:41 picassette volumio[844]: info: Loading plugin "system"... Oct 30 20:24:41 picassette nmbd[716]: [2018/10/30 20:24:41.126252, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 30 20:24:41 picassette nmbd[716]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 30 20:24:41 picassette volumio[844]: info: Loading plugin "appearance"... Oct 30 20:24:41 picassette nmbd[716]: [2018/10/30 20:24:41.144223, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:24:41 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:24:41 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "network"... Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "services"... Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "alsa_controller"... Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "wizard"... Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "volumio_command_line_client"... Oct 30 20:24:42 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:42 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:42 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:42 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:42 picassette volumio[844]: info: Loading plugin "upnp"... Oct 30 20:24:43 picassette volumio[844]: info: [1540931083008] Starting Upmpd Daemon Oct 30 20:24:43 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:24:43 picassette volumio[844]: info: Loading plugin "my_music"... Oct 30 20:24:43 picassette volumio[844]: info: Loading plugin "mpd"... Oct 30 20:24:43 picassette volumio[844]: info: Loading plugin "upnp_browser"... Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "networkfs"... Oct 30 20:24:44 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "alarm-clock"... Oct 30 20:24:44 picassette sudo[869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //PIVPN/NAS1-Public\Music /mnt/NAS/NAS1-Public Oct 30 20:24:44 picassette sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:24:44 picassette kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 30 20:24:44 picassette kernel: Key type cifs.spnego registered Oct 30 20:24:44 picassette kernel: Key type cifs.idmap registered Oct 30 20:24:44 picassette kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "airplay_emulation"... Oct 30 20:24:44 picassette volumio[844]: info: [1540931084556] Starting Shairport Sync Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "last_100"... Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "webradio"... Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "i2s_dacs"... Oct 30 20:24:44 picassette volumio[844]: info: I2S DAC not set, start Auto-detection Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "volumiodiscovery"... Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 20:24:44 picassette node[844]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 30 20:24:44 picassette node[844]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:24:44 picassette node[844]: *** WARNING *** For more information see Oct 30 20:24:44 picassette node[844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 20:24:44 picassette node[844]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:24:44 picassette node[844]: *** WARNING *** For more information see Oct 30 20:24:44 picassette kernel: CIFS VFS: ioctl error in smb2_get_dfs_refer rc=-2 Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** For more information see Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 30 20:24:44 picassette volumio[844]: *** WARNING *** For more information see Oct 30 20:24:44 picassette volumio[844]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 30 20:24:44 picassette volumio[844]: Discovery: StartAdv! undefined Oct 30 20:24:44 picassette volumio[844]: Discovery: Started advertising... picassette - undefined Oct 30 20:24:44 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "albumart"... Oct 30 20:24:44 picassette volumio[844]: info: Plugin example_plugin is not enabled Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "updater_comm"... Oct 30 20:24:44 picassette sudo[869]: pam_unix(sudo:session): session closed for user root Oct 30 20:24:44 picassette volumio[844]: info: Plugin mpdemulation is not enabled Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "rest_api"... Oct 30 20:24:44 picassette volumio[844]: info: Loading plugin "websocket"... Oct 30 20:24:44 picassette volumio[844]: info: ___________ START PLUGINS ___________ Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:24:45 picassette volumio[844]: info: [1540931085004] CoreMusicLibrary::Adding element Media Servers Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:24:45 picassette volumio[844]: info: [1540931085019] CoreMusicLibrary::Adding element Last_100 Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 30 20:24:45 picassette volumio[844]: info: [1540931085022] CoreMusicLibrary::Adding element Webradio Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 30 20:24:45 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:45 picassette volumio[844]: info: ----- MyVolumio plugins startup ---- Oct 30 20:24:45 picassette volumio[844]: info: ------------------------------------------- Oct 30 20:24:45 picassette volumio[844]: info: Loading plugins from folder /myvolumio/plugins Oct 30 20:24:45 picassette volumio[844]: info: Loading plugins from folder /data/myvolumio/plugins Oct 30 20:24:45 picassette volumio[844]: info: Loading i18n strings for locale en Oct 30 20:24:45 picassette volumio[844]: Updating browse sources language Oct 30 20:24:45 picassette volumio[844]: Cannot find translation for sourceMedia Servers Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::initPlayerControls Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:24:45 picassette volumio[844]: info: BOOT COMPLETED Oct 30 20:24:45 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:24:45 picassette volumio[844]: Forking 3 albumart workers Oct 30 20:24:45 picassette volumio-remote-updater[529]: [2018-10-30 20:24:45] [connect] Successful connection Oct 30 20:24:46 picassette volumio[844]: Starting albumart workers Oct 30 20:24:46 picassette volumio[844]: Starting albumart workers Oct 30 20:24:46 picassette volumio[844]: Starting albumart workers Oct 30 20:33:09 picassette systemd[1]: Time has been changed Oct 30 20:33:11 picassette volumio[844]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 30 20:33:11 picassette volumio[844]: info: CoreCommandRouter::Close All Modals sent Oct 30 20:33:11 picassette volumio[844]: info: CoreCommandRouter::Close All Modals sent Oct 30 20:33:11 picassette volumio[844]: Express server listening on port 3000 Oct 30 20:33:11 picassette volumio[844]: Volumio Calling Home Oct 30 20:33:11 picassette volumio[844]: info: CoreStateMachine::resetVolumioState Oct 30 20:33:11 picassette volumio[844]: info: CoreStateMachine::getcurrentVolume Oct 30 20:33:11 picassette volumio[844]: info: CoreCommandRouter::volumioRetrievevolume Oct 30 20:33:11 picassette volumio[844]: info: CoreStateMachine::updateTrackBlock Oct 30 20:33:11 picassette volumio[844]: info: CorePlayQueue::getTrackBlock Oct 30 20:33:11 picassette volumio[844]: info: Setting Device type: Raspberry PI Oct 30 20:33:11 picassette volumio[844]: info: MPD running with PID559 ,establishing connection Oct 30 20:33:11 picassette sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 30 20:33:11 picassette sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:33:11 picassette sudo[962]: pam_unix(sudo:session): session closed for user root Oct 30 20:33:12 picassette volumio[844]: info: VolumeController:: Volume=40 Mute =false Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::pushState Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:33:12 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::volumioPushState Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 20:33:12 picassette volumio[844]: info: interfaceApi::pushState Oct 30 20:33:12 picassette volumio[844]: info: Volumio called home Oct 30 20:33:12 picassette volumio[844]: info: Pushing Favourites {"service":"mpd","uri":"","favourite":false} Oct 30 20:33:12 picassette volumio[844]: info: Reloading queue from file Oct 30 20:33:12 picassette volumio[844]: info: Oct 30 20:33:12 picassette volumio[844]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Oct 30 20:33:12 picassette volumio[844]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Oct 30 20:33:12 picassette volumio[844]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Oct 30 20:33:12 picassette volumio[844]: at emitOne (events.js:116:13) Oct 30 20:33:12 picassette volumio[844]: at Socket.emit (events.js:211:7) Oct 30 20:33:12 picassette volumio[844]: at addChunk (_stream_readable.js:263:12) Oct 30 20:33:12 picassette volumio[844]: at readableAddChunk (_stream_readable.js:246:13) Oct 30 20:33:12 picassette volumio[844]: at Socket.Readable.push (_stream_readable.js:208:10) Oct 30 20:33:12 picassette volumio[844]: at Pipe.onread (net.js:607:20) Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::setRepeat null single undefined Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::pushState Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:33:12 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::volumioPushState Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 20:33:12 picassette volumio[844]: info: interfaceApi::pushState Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::setRandom null Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::pushState Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:33:12 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::volumioPushState Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 20:33:12 picassette volumio[844]: info: interfaceApi::pushState Oct 30 20:33:12 picassette volumio[844]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/NAS1-Public/Ane Brun/Ane Brun - Leave Me Breathless (2017, Balloon Ranger)/02 - Always on My Mind.flac","favourite":false} Oct 30 20:33:12 picassette volumio[844]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/NAS1-Public/Ane Brun/Ane Brun - Leave Me Breathless (2017, Balloon Ranger)/02 - Always on My Mind.flac","favourite":false} Oct 30 20:33:12 picassette sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Oct 30 20:33:12 picassette sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:33:12 picassette systemd[1]: Starting ShairportSync AirTunes receiver... Oct 30 20:33:12 picassette systemd[1]: Started ShairportSync AirTunes receiver. Oct 30 20:33:12 picassette sudo[972]: pam_unix(sudo:session): session closed for user root Oct 30 20:33:12 picassette shairport-sync[977]: Successful Startup Oct 30 20:33:12 picassette volumio[844]: Discovery: adding 84e97942-dc53-4d07-9c91-bf7f5428b7b9 Oct 30 20:33:12 picassette volumio[844]: info: mDNS: Found device picassette Oct 30 20:33:12 picassette volumio[844]: info: [1540931592361] Shairport-Sync Started Oct 30 20:33:12 picassette volumio[844]: Error adding Membership: Error: addMembership EINVAL Oct 30 20:33:12 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:33:12 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:33:12 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:33:13 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 30 20:33:13 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 30 20:33:14 picassette sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 30 20:33:14 picassette sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 30 20:33:14 picassette systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 30 20:33:14 picassette systemd[1]: Started UPnP Renderer front-end to MPD. Oct 30 20:33:14 picassette sudo[992]: pam_unix(sudo:session): session closed for user root Oct 30 20:33:14 picassette volumio[844]: info: Upmpdcli Daemon Started Oct 30 20:33:21 picassette dhcpcd[444]: timed out Oct 30 20:33:21 picassette dhcpcd[444]: exited Oct 30 20:33:21 picassette ifup[410]: dhcpcd[444]: timed out Oct 30 20:33:21 picassette ifup[410]: dhcpcd[444]: exited Oct 30 20:33:21 picassette ifup[410]: Failed to bring up eth0. Oct 30 20:33:21 picassette volumio-remote-updater[529]: [2018-10-30 20:33:21] [connect] Successful connection Oct 30 20:33:21 picassette volumio-remote-updater[529]: [2018-10-30 20:33:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1540931601 101 Oct 30 20:33:28 picassette nmbd[716]: [2018/10/30 20:33:28.448446, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:33:28 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:33:28 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:35:06 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:35:06 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:35:06 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:35:06 picassette volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 20:35:06 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 20:35:06 picassette volumio[844]: Plugin multiroom or method getMultiroom not found Oct 30 20:35:06 picassette volumio[844]: info: Listing playlists Oct 30 20:38:18 picassette nmbd[716]: [2018/10/30 20:38:18.946387, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:38:18 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:38:18 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:43:26 picassette nmbd[716]: [2018/10/30 20:43:26.059783, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:43:26 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:43:26 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:45:31 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:45:31 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:45:31 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:45:33 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:45:33 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:45:33 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:45:33 picassette volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 20:45:33 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 20:45:33 picassette volumio[844]: Plugin multiroom or method getMultiroom not found Oct 30 20:45:33 picassette volumio[844]: info: Listing playlists Oct 30 20:47:26 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:47:26 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:47:26 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:47:27 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:47:27 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:47:27 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:47:27 picassette volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 20:47:27 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 20:47:27 picassette volumio[844]: Plugin multiroom or method getMultiroom not found Oct 30 20:47:27 picassette volumio[844]: info: Listing playlists Oct 30 20:47:32 picassette systemd[1]: Starting Cleanup of Temporary Directories... Oct 30 20:47:32 picassette systemd[1]: Started Cleanup of Temporary Directories. Oct 30 20:48:33 picassette sshd[7387]: Accepted password for volumio from 192.168.1.102 port 59160 ssh2 Oct 30 20:48:33 picassette sshd[7387]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 30 20:48:33 picassette systemd[1]: Starting user-1000.slice. Oct 30 20:48:33 picassette systemd[1]: Created slice user-1000.slice. Oct 30 20:48:33 picassette systemd[1]: Starting User Manager for UID 1000... Oct 30 20:48:33 picassette systemd[1]: Starting Session c1 of user volumio. Oct 30 20:48:33 picassette systemd-logind[535]: New session c1 of user volumio. Oct 30 20:48:33 picassette systemd[1]: Started Session c1 of user volumio. Oct 30 20:48:33 picassette systemd[7414]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Oct 30 20:48:33 picassette systemd[7414]: Starting Paths. Oct 30 20:48:33 picassette systemd[7414]: Reached target Paths. Oct 30 20:48:33 picassette systemd[7414]: Starting Timers. Oct 30 20:48:33 picassette systemd[7414]: Reached target Timers. Oct 30 20:48:33 picassette systemd[7414]: Starting Sockets. Oct 30 20:48:33 picassette systemd[7414]: Reached target Sockets. Oct 30 20:48:33 picassette systemd[7414]: Starting Basic System. Oct 30 20:48:33 picassette systemd[7414]: Reached target Basic System. Oct 30 20:48:33 picassette systemd[7414]: Starting Default. Oct 30 20:48:33 picassette systemd[7414]: Reached target Default. Oct 30 20:48:33 picassette systemd[7414]: Startup finished in 31ms. Oct 30 20:48:33 picassette systemd[1]: Started User Manager for UID 1000. Oct 30 20:48:33 picassette sshd[7387]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Oct 30 20:48:33 picassette sshd[7387]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Oct 30 20:48:44 picassette nmbd[716]: [2018/10/30 20:48:44.694612, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:48:44 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:48:44 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:48:55 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:48:55 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 20:48:55 picassette volumio[844]: Plugin multiroom or method getMultiroom not found Oct 30 20:48:55 picassette volumio[844]: info: Listing playlists Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:48:55 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:48:55 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::volumioGetBrowseSources Oct 30 20:48:55 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 30 20:48:55 picassette volumio[844]: Plugin multiroom or method getMultiroom not found Oct 30 20:48:55 picassette volumio[844]: info: Listing playlists Oct 30 20:49:09 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:49:09 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:49:09 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:49:57 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 30 20:52:58 picassette sudo[9277]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl Oct 30 20:52:58 picassette sudo[9277]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Oct 30 20:52:58 picassette sudo[9277]: pam_unix(sudo:session): session closed for user root Oct 30 20:53:42 picassette nmbd[716]: [2018/10/30 20:53:42.443451, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 30 20:53:42 picassette nmbd[716]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.101 for name WORKGROUP<1d>. Oct 30 20:53:42 picassette nmbd[716]: This response was from IP 192.168.1.100, reporting an IP address of 192.168.1.100. Oct 30 20:54:07 picassette volumio[844]: info: CoreCommandRouter::volumioGetState Oct 30 20:54:07 picassette volumio[844]: info: CoreStateMachine::getState Oct 30 20:54:07 picassette volumio[844]: info: CorePlayQueue::getTrack 0 Oct 30 20:54:07 picassette volumio[844]: info: CoreCommandRouter::volumioGetQueue Oct 30 20:54:07 picassette volumio[844]: info: CoreStateMachine::getQueue Oct 30 20:54:07 picassette volumio[844]: info: CorePlayQueue::getQueue Oct 30 20:54:33 picassette volumio[844]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Oct 30 20:54:33 picassette volumio[844]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Oct 30 20:54:34 picassette sudo[9978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Oct 30 20:54:34 picassette sudo[9978]: pam_unix(sudo:session): session opened for user root by (uid=0)