search

MiczFlor / RPi-Jukebox-RFID

A Raspberry Pi jukebox, playing local music, podcasts, web radio and streams triggered by RFID cards, web app or home automation. All plug and play via USB. GPIO scripts available.
http://phoniebox.de
MIT License
1.34k stars 396 forks source link

🐛 | Startup sound did not work #1178

Closed scharc closed 1 year ago

scharc commented 3 years ago

Bug

What I did

Upgraded from 2.0 to 2.2 with the one line installer

What happened

startup sound stopped working

I expected this to happen

startup sound working

Further information that might help

syslog:

Dec  6 22:32:10 phoniebox bash[952]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
Dec  6 22:32:10 phoniebox bash[952]: #011version 1.25.10; written and copyright by Michael Hipp and others
Dec  6 22:32:10 phoniebox bash[952]: #011free software (LGPL) without any warranty but with best wishes
Dec  6 22:32:10 phoniebox bash[952]: Cannot connect to server socket err = No such file or directory
Dec  6 22:32:10 phoniebox bash[952]: Cannot connect to server request channel
Dec  6 22:32:10 phoniebox bash[952]: jack server is not running or cannot be started
Dec  6 22:32:10 phoniebox bash[952]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec  6 22:32:10 phoniebox bash[952]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec  6 22:32:10 phoniebox bash[952]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 42:  1054 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Dec  6 22:32:10 phoniebox bash[952]: Updating DB (#1) ...
Dec  6 22:32:10 phoniebox bash[952]: volume: 15%   repeat: off   random: off   single: off   consume: off

I run the startup script manually from the command line and got the same error...

When I run the script as root it works, so my fix is just prefixing the mpg123 line with sudo in startup-scripts.sh

I can give more details if needed.

fatdreads commented 3 years ago

maybe this helps: https://github.com/MiczFlor/RPi-Jukebox-RFID/issues/1167

s-martin commented 3 years ago

Can you provide more of syslog before that snippet? I’m missing a couple of log entries fromstartup-script.sh here. Looks like the variable {mpgvolume} was not expanded.

scharc commented 3 years ago

Sure: This is the second boot. In my first bug report I copied a log file where I forgot to raise the startvolume from 0 to something useful. On this boot I corrected my failure:

Dec  6 22:39:11 phoniebox kernel: [    0.000000] Booting Linux on physical CPU 0x0
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Linux version 5.4.79-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1373 SMP Mon Nov 23 13:22:33 GMT 2020
Dec  6 22:39:11 phoniebox kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Dec  6 22:39:11 phoniebox kernel: [    0.000000] CPU: div instructions available: patching division code
Dec  6 22:39:11 phoniebox kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Dec  6 22:39:11 phoniebox kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Memory policy: Data cache writealloc
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
Dec  6 22:39:11 phoniebox kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Dec  6 22:39:11 phoniebox kernel: [    0.000000] On node 0 totalpages: 242688
Dec  6 22:39:11 phoniebox kernel: [    0.000000]   Normal zone: 2133 pages used for memmap
Dec  6 22:39:11 phoniebox kernel: [    0.000000]   Normal zone: 0 pages reserved
Dec  6 22:39:11 phoniebox kernel: [    0.000000]   Normal zone: 242688 pages, LIFO batch:63
Dec  6 22:39:11 phoniebox kernel: [    0.000000] percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920
Dec  6 22:39:11 phoniebox kernel: [    0.000000] pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096
Dec  6 22:39:11 phoniebox kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=7d4d7400-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Dec  6 22:39:11 phoniebox kernel: [    0.000000] Memory: 880476K/970752K available (9216K kernel code, 698K rwdata, 2608K rodata, 1024K init, 827K bss, 24740K reserved, 65536K cma-reserved)
Dec  6 22:39:11 phoniebox kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Dec  6 22:39:11 phoniebox kernel: [    0.000000] ftrace: allocating 29201 entries in 58 pages
Dec  6 22:39:11 phoniebox kernel: [    0.000000] rcu: Hierarchical RCU implementation.
Dec  6 22:39:11 phoniebox kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Dec  6 22:39:11 phoniebox kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Dec  6 22:39:11 phoniebox kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x324/0x4f8 with crng_init=0
Dec  6 22:39:11 phoniebox kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Dec  6 22:39:11 phoniebox kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Dec  6 22:39:11 phoniebox kernel: [    0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Dec  6 22:39:11 phoniebox kernel: [    0.000022] Switching to timer-based delay loop, resolution 52ns
Dec  6 22:39:11 phoniebox kernel: [    0.000287] Console: colour dummy device 80x30
Dec  6 22:39:11 phoniebox kernel: [    0.000881] printk: console [tty1] enabled
Dec  6 22:39:11 phoniebox kernel: [    0.000946] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Dec  6 22:39:11 phoniebox kernel: [    0.000994] pid_max: default: 32768 minimum: 301
Dec  6 22:39:11 phoniebox kernel: [    0.001202] LSM: Security Framework initializing
Dec  6 22:39:11 phoniebox kernel: [    0.001438] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    0.001484] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    0.002952] Disabling memory control group subsystem
Dec  6 22:39:11 phoniebox kernel: [    0.003092] CPU: Testing write buffer coherency: ok
Dec  6 22:39:11 phoniebox kernel: [    0.003679] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Dec  6 22:39:11 phoniebox kernel: [    0.004640] Setting up static identity map for 0x100000 - 0x10003c
Dec  6 22:39:11 phoniebox kernel: [    0.004858] rcu: Hierarchical SRCU implementation.
Dec  6 22:39:11 phoniebox kernel: [    0.005554] smp: Bringing up secondary CPUs ...
Dec  6 22:39:11 phoniebox kernel: [    0.006699] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Dec  6 22:39:11 phoniebox kernel: [    0.007969] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Dec  6 22:39:11 phoniebox kernel: [    0.009134] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Dec  6 22:39:11 phoniebox kernel: [    0.009287] smp: Brought up 1 node, 4 CPUs
Dec  6 22:39:11 phoniebox kernel: [    0.009377] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Dec  6 22:39:11 phoniebox kernel: [    0.009406] CPU: All CPU(s) started in HYP mode.
Dec  6 22:39:11 phoniebox kernel: [    0.009431] CPU: Virtualization extensions available.
Dec  6 22:39:11 phoniebox kernel: [    0.010531] devtmpfs: initialized
Dec  6 22:39:11 phoniebox kernel: [    0.026652] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Dec  6 22:39:11 phoniebox kernel: [    0.026957] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Dec  6 22:39:11 phoniebox kernel: [    0.027015] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    0.030365] pinctrl core: initialized pinctrl subsystem
Dec  6 22:39:11 phoniebox kernel: [    0.031486] NET: Registered protocol family 16
Dec  6 22:39:11 phoniebox kernel: [    0.035692] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Dec  6 22:39:11 phoniebox kernel: [    0.040543] audit: initializing netlink subsys (disabled)
Dec  6 22:39:11 phoniebox kernel: [    0.040843] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
Dec  6 22:39:11 phoniebox kernel: [    0.042186] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Dec  6 22:39:11 phoniebox kernel: [    0.042225] hw-breakpoint: maximum watchpoint size is 8 bytes.
Dec  6 22:39:11 phoniebox kernel: [    0.042457] Serial: AMBA PL011 UART driver
Dec  6 22:39:11 phoniebox kernel: [    0.044442] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Dec  6 22:39:11 phoniebox kernel: [    0.070103] raspberrypi-firmware soc:firmware: Attached to firmware from 2020-11-30 22:13, variant start
Dec  6 22:39:11 phoniebox kernel: [    0.080117] raspberrypi-firmware soc:firmware: Firmware hash is ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c
Dec  6 22:39:11 phoniebox kernel: [    0.131113] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Dec  6 22:39:11 phoniebox kernel: [    0.133306] SCSI subsystem initialized
Dec  6 22:39:11 phoniebox kernel: [    0.133568] usbcore: registered new interface driver usbfs
Dec  6 22:39:11 phoniebox kernel: [    0.133650] usbcore: registered new interface driver hub
Dec  6 22:39:11 phoniebox kernel: [    0.133805] usbcore: registered new device driver usb
Dec  6 22:39:11 phoniebox kernel: [    0.135672] clocksource: Switched to clocksource arch_sys_counter
Dec  6 22:39:11 phoniebox kernel: [    1.395889] VFS: Disk quotas dquot_6.6.0
Dec  6 22:39:11 phoniebox kernel: [    1.396027] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Dec  6 22:39:11 phoniebox kernel: [    1.396242] FS-Cache: Loaded
Dec  6 22:39:11 phoniebox kernel: [    1.396528] CacheFiles: Loaded
Dec  6 22:39:11 phoniebox kernel: [    1.407792] thermal_sys: Registered thermal governor 'step_wise'
Dec  6 22:39:11 phoniebox kernel: [    1.408181] NET: Registered protocol family 2
Dec  6 22:39:11 phoniebox kernel: [    1.409201] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    1.409276] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    1.409418] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    1.409628] TCP: Hash tables configured (established 8192 bind 8192)
Dec  6 22:39:11 phoniebox kernel: [    1.409815] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    1.409889] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Dec  6 22:39:11 phoniebox kernel: [    1.410200] NET: Registered protocol family 1
Dec  6 22:39:11 phoniebox kernel: [    1.410993] RPC: Registered named UNIX socket transport module.
Dec  6 22:39:11 phoniebox kernel: [    1.411025] RPC: Registered udp transport module.
Dec  6 22:39:11 phoniebox kernel: [    1.411052] RPC: Registered tcp transport module.
Dec  6 22:39:11 phoniebox kernel: [    1.411078] RPC: Registered tcp NFSv4.1 backchannel transport module.
Dec  6 22:39:11 phoniebox kernel: [    1.412730] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Dec  6 22:39:11 phoniebox kernel: [    1.416127] Initialise system trusted keyrings
Dec  6 22:39:11 phoniebox kernel: [    1.416418] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Dec  6 22:39:11 phoniebox kernel: [    1.428038] FS-Cache: Netfs 'nfs' registered for caching
Dec  6 22:39:11 phoniebox kernel: [    1.428957] NFS: Registering the id_resolver key type
Dec  6 22:39:11 phoniebox kernel: [    1.429024] Key type id_resolver registered
Dec  6 22:39:11 phoniebox kernel: [    1.429051] Key type id_legacy registered
Dec  6 22:39:11 phoniebox kernel: [    1.429087] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Dec  6 22:39:11 phoniebox kernel: [    1.430396] Key type asymmetric registered
Dec  6 22:39:11 phoniebox kernel: [    1.430426] Asymmetric key parser 'x509' registered
Dec  6 22:39:11 phoniebox kernel: [    1.430505] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Dec  6 22:39:11 phoniebox kernel: [    1.430543] io scheduler mq-deadline registered
Dec  6 22:39:11 phoniebox kernel: [    1.430571] io scheduler kyber registered
Dec  6 22:39:11 phoniebox kernel: [    1.434031] bcm2708_fb soc:fb: FB found 1 display(s)
Dec  6 22:39:11 phoniebox kernel: [    1.446578] Console: switching to colour frame buffer device 82x26
Dec  6 22:39:11 phoniebox kernel: [    1.453674] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
Dec  6 22:39:11 phoniebox kernel: [    1.463565] bcm2835-rng 3f104000.rng: hwrng registered
Dec  6 22:39:11 phoniebox kernel: [    1.466714] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Dec  6 22:39:11 phoniebox kernel: [    1.472538] vc-sm: Videocore shared memory driver
Dec  6 22:39:11 phoniebox kernel: [    1.475576] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Dec  6 22:39:11 phoniebox kernel: [    1.493581] brd: module loaded
Dec  6 22:39:11 phoniebox kernel: [    1.508709] loop: module loaded
Dec  6 22:39:11 phoniebox kernel: [    1.512615] Loading iSCSI transport class v2.0-870.
Dec  6 22:39:11 phoniebox kernel: [    1.516152] libphy: Fixed MDIO Bus: probed
Dec  6 22:39:11 phoniebox kernel: [    1.518828] usbcore: registered new interface driver lan78xx
Dec  6 22:39:11 phoniebox kernel: [    1.521468] usbcore: registered new interface driver smsc95xx
Dec  6 22:39:11 phoniebox kernel: [    1.524007] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Dec  6 22:39:11 phoniebox kernel: [    2.254836] Core Release: 2.80a
Dec  6 22:39:11 phoniebox kernel: [    2.257283] Setting default values for core params
Dec  6 22:39:11 phoniebox kernel: [    2.259725] Finished setting default values for core params
Dec  6 22:39:11 phoniebox kernel: [    2.462593] Using Buffer DMA mode
Dec  6 22:39:11 phoniebox kernel: [    2.465053] Periodic Transfer Interrupt Enhancement - disabled
Dec  6 22:39:11 phoniebox kernel: [    2.467581] Multiprocessor Interrupt Enhancement - disabled
Dec  6 22:39:11 phoniebox kernel: [    2.470063] OTG VER PARAM: 0, OTG VER FLAG: 0
Dec  6 22:39:11 phoniebox kernel: [    2.472475] Dedicated Tx FIFOs mode
Dec  6 22:39:11 phoniebox kernel: [    2.475446] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7514000 dma = 0xf7514000 len=9024
Dec  6 22:39:11 phoniebox kernel: [    2.480223] FIQ FSM acceleration enabled for :
Dec  6 22:39:11 phoniebox kernel: [    2.480223] Non-periodic Split Transactions
Dec  6 22:39:11 phoniebox kernel: [    2.480223] Periodic Split Transactions
Dec  6 22:39:11 phoniebox kernel: [    2.480223] High-Speed Isochronous Endpoints
Dec  6 22:39:11 phoniebox kernel: [    2.480223] Interrupt/Control Split Transaction hack enabled
Dec  6 22:39:11 phoniebox kernel: [    2.491369] dwc_otg: Microframe scheduler enabled
Dec  6 22:39:11 phoniebox kernel: [    2.491435] WARN::hcd_init_fiq:457: FIQ on core 1
Dec  6 22:39:11 phoniebox kernel: [    2.493687] WARN::hcd_init_fiq:458: FIQ ASM at 8070b948 length 36
Dec  6 22:39:11 phoniebox kernel: [    2.495948] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
Dec  6 22:39:11 phoniebox kernel: [    2.498206] dwc_otg 3f980000.usb: DWC OTG Controller
Dec  6 22:39:11 phoniebox kernel: [    2.500522] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Dec  6 22:39:11 phoniebox kernel: [    2.502903] dwc_otg 3f980000.usb: irq 56, io mem 0x00000000
Dec  6 22:39:11 phoniebox kernel: [    2.505228] Init: Port Power? op_state=1
Dec  6 22:39:11 phoniebox kernel: [    2.507521] Init: Power Port (0)
Dec  6 22:39:11 phoniebox kernel: [    2.510072] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
Dec  6 22:39:11 phoniebox kernel: [    2.514617] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Dec  6 22:39:11 phoniebox kernel: [    2.517078] usb usb1: Product: DWC OTG Controller
Dec  6 22:39:11 phoniebox kernel: [    2.519468] usb usb1: Manufacturer: Linux 5.4.79-v7+ dwc_otg_hcd
Dec  6 22:39:11 phoniebox kernel: [    2.521904] usb usb1: SerialNumber: 3f980000.usb
Dec  6 22:39:11 phoniebox kernel: [    2.524923] hub 1-0:1.0: USB hub found
Dec  6 22:39:11 phoniebox kernel: [    2.527260] hub 1-0:1.0: 1 port detected
Dec  6 22:39:11 phoniebox kernel: [    2.530217] dwc_otg: FIQ enabled
Dec  6 22:39:11 phoniebox kernel: [    2.530227] dwc_otg: NAK holdoff enabled
Dec  6 22:39:11 phoniebox kernel: [    2.530236] dwc_otg: FIQ split-transaction FSM enabled
Dec  6 22:39:11 phoniebox kernel: [    2.530251] Module dwc_common_port init
Dec  6 22:39:11 phoniebox kernel: [    2.530600] usbcore: registered new interface driver usb-storage
Dec  6 22:39:11 phoniebox kernel: [    2.533087] mousedev: PS/2 mouse device common for all mice
Dec  6 22:39:11 phoniebox kernel: [    2.536571] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Dec  6 22:39:11 phoniebox kernel: [    2.541887] sdhci: Secure Digital Host Controller Interface driver
Dec  6 22:39:11 phoniebox kernel: [    2.544326] sdhci: Copyright(c) Pierre Ossman
Dec  6 22:39:11 phoniebox kernel: [    2.547329] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Dec  6 22:39:11 phoniebox kernel: [    2.550500] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Dec  6 22:39:11 phoniebox kernel: [    2.553302] sdhci-pltfm: SDHCI platform and OF driver helper
Dec  6 22:39:11 phoniebox kernel: [    2.557742] ledtrig-cpu: registered to indicate activity on CPUs
Dec  6 22:39:11 phoniebox kernel: [    2.560687] hidraw: raw HID events driver (C) Jiri Kosina
Dec  6 22:39:11 phoniebox kernel: [    2.563436] usbcore: registered new interface driver usbhid
Dec  6 22:39:11 phoniebox kernel: [    2.566063] usbhid: USB HID core driver
Dec  6 22:39:11 phoniebox kernel: [    2.569623] vchiq: vchiq_init_state: slot_zero = (ptrval)
Dec  6 22:39:11 phoniebox kernel: [    2.573982] [vc_sm_connected_init]: start
Dec  6 22:39:11 phoniebox kernel: [    2.582669] [vc_sm_connected_init]: end - returning 0
Dec  6 22:39:11 phoniebox kernel: [    2.587066] Initializing XFRM netlink socket
Dec  6 22:39:11 phoniebox kernel: [    2.589448] NET: Registered protocol family 17
Dec  6 22:39:11 phoniebox kernel: [    2.591921] Key type dns_resolver registered
Dec  6 22:39:11 phoniebox kernel: [    2.594851] Registering SWP/SWPB emulation handler
Dec  6 22:39:11 phoniebox kernel: [    2.597573] registered taskstats version 1
Dec  6 22:39:11 phoniebox kernel: [    2.599945] Loading compiled-in X.509 certificates
Dec  6 22:39:11 phoniebox kernel: [    2.602780] Key type ._fscrypt registered
Dec  6 22:39:11 phoniebox kernel: [    2.605091] Key type .fscrypt registered
Dec  6 22:39:11 phoniebox kernel: [    2.618158] uart-pl011 3f201000.serial: cts_event_workaround enabled
Dec  6 22:39:11 phoniebox kernel: [    2.620579] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
Dec  6 22:39:11 phoniebox kernel: [    2.627151] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Dec  6 22:39:11 phoniebox kernel: [    2.631029] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Dec  6 22:39:11 phoniebox kernel: [    2.633430] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Dec  6 22:39:11 phoniebox kernel: [    2.662361] sdhost: log_buf @ (ptrval) (f7513000)
Dec  6 22:39:11 phoniebox kernel: [    2.700527] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Dec  6 22:39:11 phoniebox kernel: [    2.704511] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec  6 22:39:11 phoniebox kernel: [    2.708424] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec  6 22:39:11 phoniebox kernel: [    2.713511] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Dec  6 22:39:11 phoniebox kernel: [    2.715627] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Dec  6 22:39:11 phoniebox kernel: [    2.720395] of_cfs_init
Dec  6 22:39:11 phoniebox kernel: [    2.722597] of_cfs_init: OK
Dec  6 22:39:11 phoniebox kernel: [    2.725801] Waiting for root device PARTUUID=7d4d7400-02...
Dec  6 22:39:11 phoniebox kernel: [    2.745837] Indeed it is in host mode hprt0 = 00021501
Dec  6 22:39:11 phoniebox kernel: [    2.818856] random: fast init done
Dec  6 22:39:11 phoniebox kernel: [    2.869076] mmc0: host does not support reading read-only switch, assuming write-enable
Dec  6 22:39:11 phoniebox kernel: [    2.877274] mmc1: new high speed SDIO card at address 0001
Dec  6 22:39:11 phoniebox kernel: [    2.880267] mmc0: new high speed SDHC card at address aaaa
Dec  6 22:39:11 phoniebox kernel: [    2.883512] mmcblk0: mmc0:aaaa SE32G 29.7 GiB
Dec  6 22:39:11 phoniebox kernel: [    2.890230]  mmcblk0: p1 p2
Dec  6 22:39:11 phoniebox kernel: [    2.920113] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Dec  6 22:39:11 phoniebox kernel: [    2.924296] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Dec  6 22:39:11 phoniebox kernel: [    2.936054] devtmpfs: mounted
Dec  6 22:39:11 phoniebox kernel: [    2.944742] Freeing unused kernel memory: 1024K
Dec  6 22:39:11 phoniebox kernel: [    2.947362] Run /sbin/init as init process
Dec  6 22:39:11 phoniebox kernel: [    2.955720] usb 1-1: new high-speed USB device number 2 using dwc_otg
Dec  6 22:39:11 phoniebox kernel: [    2.958318] Indeed it is in host mode hprt0 = 00001101
Dec  6 22:39:11 phoniebox kernel: [    3.196102] usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Dec  6 22:39:11 phoniebox kernel: [    3.201245] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec  6 22:39:11 phoniebox kernel: [    3.204826] hub 1-1:1.0: USB hub found
Dec  6 22:39:11 phoniebox kernel: [    3.207737] hub 1-1:1.0: 4 ports detected
Dec  6 22:39:11 phoniebox kernel: [    3.525735] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Dec  6 22:39:11 phoniebox kernel: [    3.656118] usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Dec  6 22:39:11 phoniebox kernel: [    3.661515] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec  6 22:39:11 phoniebox kernel: [    3.665270] hub 1-1.1:1.0: USB hub found
Dec  6 22:39:11 phoniebox kernel: [    3.668540] hub 1-1.1:1.0: 3 ports detected
Dec  6 22:39:11 phoniebox kernel: [    3.686098] NET: Registered protocol family 10
Dec  6 22:39:11 phoniebox kernel: [    3.690240] Segment Routing with IPv6
Dec  6 22:39:11 phoniebox kernel: [    3.985849] usb 1-1.1.2: new low-speed USB device number 4 using dwc_otg
Dec  6 22:39:11 phoniebox kernel: [    4.127565] usb 1-1.1.2: New USB device found, idVendor=16c0, idProduct=27db, bcdDevice= 0.01
Dec  6 22:39:11 phoniebox kernel: [    4.133085] usb 1-1.1.2: New USB device strings: Mfr=1, Product=0, SerialNumber=0
Dec  6 22:39:11 phoniebox kernel: [    4.138828] usb 1-1.1.2: Manufacturer: HXGCoLtd
Dec  6 22:39:11 phoniebox kernel: [    4.157446] input: HXGCoLtd Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:16C0:27DB.0001/input/input0
Dec  6 22:39:11 phoniebox kernel: [    4.226598] hid-generic 0003:16C0:27DB.0001: input,hiddev96,hidraw0: USB HID v1.10 Keyboard [HXGCoLtd] on usb-3f980000.usb-1.1.2/input0
Dec  6 22:39:11 phoniebox kernel: [    4.525741] usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg
Dec  6 22:39:11 phoniebox kernel: [    4.656331] usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
Dec  6 22:39:11 phoniebox kernel: [    4.662638] usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec  6 22:39:11 phoniebox kernel: [    4.817272] random: systemd: uninitialized urandom read (16 bytes read)
Dec  6 22:39:11 phoniebox kernel: [    4.841765] random: systemd: uninitialized urandom read (16 bytes read)
Dec  6 22:39:11 phoniebox kernel: [    4.854179] random: systemd: uninitialized urandom read (16 bytes read)
Dec  6 22:39:11 phoniebox kernel: [    4.935727] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
Dec  6 22:39:11 phoniebox kernel: [    4.943724] libphy: lan78xx-mdiobus: probed
Dec  6 22:39:11 phoniebox kernel: [    4.976516] lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
Dec  6 22:39:11 phoniebox kernel: [    5.741341] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Dec  6 22:39:11 phoniebox kernel: [    6.673002] mc: Linux media interface: v0.10
Dec  6 22:39:11 phoniebox kernel: [    6.747576] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.748700] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.750090] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Dec  6 22:39:11 phoniebox kernel: [    6.750109] [vc_sm_connected_init]: start
Dec  6 22:39:11 phoniebox kernel: [    6.751602] videodev: Linux video capture interface: v2.00
Dec  6 22:39:11 phoniebox kernel: [    6.755076] bcm2835_audio bcm2835_audio: card created with 8 channels
Dec  6 22:39:11 phoniebox kernel: [    6.755601] [vc_sm_connected_init]: installed successfully
Dec  6 22:39:11 phoniebox kernel: [    6.791546] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.793469] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.798030] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.807463] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.811712] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.818101] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Dec  6 22:39:11 phoniebox kernel: [    6.832023] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Dec  6 22:39:11 phoniebox kernel: [    6.832531] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Dec  6 22:39:11 phoniebox kernel: [    6.833129] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Dec  6 22:39:11 phoniebox kernel: [    6.833454] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Dec  6 22:39:11 phoniebox kernel: [    6.833485] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Dec  6 22:39:11 phoniebox kernel: [    6.833506] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Dec  6 22:39:11 phoniebox kernel: [    6.833524] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Dec  6 22:39:11 phoniebox kernel: [    6.833542] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Dec  6 22:39:11 phoniebox kernel: [    6.833775] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Dec  6 22:39:11 phoniebox kernel: [    6.860273] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Dec  6 22:39:11 phoniebox kernel: [    6.860347] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Dec  6 22:39:11 phoniebox kernel: [    6.885145] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Dec  6 22:39:11 phoniebox kernel: [    6.885197] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Dec  6 22:39:11 phoniebox kernel: [    6.893370] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Dec  6 22:39:11 phoniebox kernel: [    6.893419] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Dec  6 22:39:11 phoniebox kernel: [    7.161172] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Dec  6 22:39:11 phoniebox kernel: [    7.346154] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Dec  6 22:39:11 phoniebox kernel: [    7.424457] brcmfmac: F1 signature read @0x18000000=0x15264345
Dec  6 22:39:11 phoniebox kernel: [    7.433449] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Dec  6 22:39:11 phoniebox kernel: [    7.433988] usbcore: registered new interface driver brcmfmac
Dec  6 22:39:11 phoniebox kernel: [    7.468801] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,3-model-b-plus.txt failed with error -2
Dec  6 22:39:11 phoniebox kernel: [    7.699177] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Dec  6 22:39:11 phoniebox kernel: [    7.704719] random: crng init done
Dec  6 22:39:11 phoniebox kernel: [    7.704734] random: 7 urandom warning(s) missed due to ratelimiting
Dec  6 22:39:11 phoniebox kernel: [    7.714952] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 23 2020 02:19:54 version 7.45.206 (r725000 CY) FWID 01-88ee44ea
Dec  6 22:39:11 phoniebox kernel: [    8.875829] Under-voltage detected! (0x00050005)
Dec  6 22:39:11 phoniebox kernel: [    9.567458] uart-pl011 3f201000.serial: no DMA platform data
Dec  6 22:39:11 phoniebox fake-hwclock[102]: Sun  6 Dec 22:39:07 UTC 2020
Dec  6 22:39:11 phoniebox systemd-fsck[126]: e2fsck 1.44.5 (15-Dec-2018)
Dec  6 22:39:11 phoniebox systemd-fsck[126]: rootfs: clean, 112214/1899328 files, 1230668/7725184 blocks
Dec  6 22:39:11 phoniebox systemd[1]: Started File System Check on Root Device.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Remount Root and Kernel File Systems...
Dec  6 22:39:11 phoniebox systemd[1]: Started udev Coldplug all Devices.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Dec  6 22:39:11 phoniebox systemd[1]: Started Helper to synchronize boot up for ifupdown.
Dec  6 22:39:11 phoniebox systemd[1]: Started Remount Root and Kernel File Systems.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Create System Users...
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Flush Journal to Persistent Storage...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Load/Save Random Seed...
Dec  6 22:39:11 phoniebox systemd[1]: Started Set the console keyboard layout.
Dec  6 22:39:11 phoniebox systemd[1]: Started Create System Users.
Dec  6 22:39:11 phoniebox systemd[1]: Started Load/Save Random Seed.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Create Static Device Nodes in /dev...
Dec  6 22:39:11 phoniebox systemd[1]: Started Flush Journal to Persistent Storage.
Dec  6 22:39:11 phoniebox systemd[1]: Started Create Static Device Nodes in /dev.
Dec  6 22:39:11 phoniebox systemd[1]: Starting udev Kernel Device Manager...
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Local File Systems (Pre).
Dec  6 22:39:11 phoniebox systemd[1]: Started udev Kernel Device Manager.
Dec  6 22:39:11 phoniebox systemd-udevd[166]: Using default interface naming scheme 'v240'.
Dec  6 22:39:11 phoniebox mtp-probe: checking bus 1, device 5: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.1"
Dec  6 22:39:11 phoniebox mtp-probe: bus: 1, device: 5 was not an MTP device
Dec  6 22:39:11 phoniebox mtp-probe: checking bus 1, device 5: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.1"
Dec  6 22:39:11 phoniebox mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2"
Dec  6 22:39:11 phoniebox mtp-probe: bus: 1, device: 4 was not an MTP device
Dec  6 22:39:11 phoniebox mtp-probe: bus: 1, device: 5 was not an MTP device
Dec  6 22:39:11 phoniebox systemd[1]: Found device /dev/serial1.
Dec  6 22:39:11 phoniebox systemd-udevd[165]: Using default interface naming scheme 'v240'.
Dec  6 22:39:11 phoniebox systemd[1]: Found device /dev/disk/by-partuuid/7d4d7400-01.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting File System Check on /dev/disk/by-partuuid/7d4d7400-01...
Dec  6 22:39:11 phoniebox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec  6 22:39:11 phoniebox systemd-udevd[167]: Using default interface naming scheme 'v240'.
Dec  6 22:39:11 phoniebox systemd[1]: Started Load/Save RF Kill Switch Status.
Dec  6 22:39:11 phoniebox systemd-udevd[169]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Dec  6 22:39:11 phoniebox systemd-fsck[269]: fsck.fat 4.1 (2017-01-24)
Dec  6 22:39:11 phoniebox systemd-fsck[269]: /dev/mmcblk0p1: 251 files, 110101/516190 clusters
Dec  6 22:39:11 phoniebox systemd[1]: Started File System Check on /dev/disk/by-partuuid/7d4d7400-01.
Dec  6 22:39:11 phoniebox systemd[1]: Mounting /boot...
Dec  6 22:39:11 phoniebox systemd[1]: Mounted /boot.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Local File Systems.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Set console font and keymap...
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Raise network interfaces...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Preprocess NFS configuration...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Create Volatile Files and Directories...
Dec  6 22:39:11 phoniebox systemd[1]: Started Set console font and keymap.
Dec  6 22:39:11 phoniebox systemd[1]: nfs-config.service: Succeeded.
Dec  6 22:39:11 phoniebox systemd[1]: Started Preprocess NFS configuration.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target NFS client services.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Remote File Systems (Pre).
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Remote File Systems.
Dec  6 22:39:11 phoniebox systemd[1]: Started Create Volatile Files and Directories.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Network Time Synchronization...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Dec  6 22:39:11 phoniebox systemd[1]: Started Update UTMP about System Boot/Shutdown.
Dec  6 22:39:11 phoniebox systemd[1]: Started Raise network interfaces.
Dec  6 22:39:11 phoniebox systemd[1]: Started Network Time Synchronization.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target System Initialization.
Dec  6 22:39:11 phoniebox systemd[1]: Listening on D-Bus System Message Bus Socket.
Dec  6 22:39:11 phoniebox systemd[1]: Listening on triggerhappy.socket.
Dec  6 22:39:11 phoniebox systemd[1]: Started Daily Cleanup of Temporary Directories.
Dec  6 22:39:11 phoniebox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Dec  6 22:39:11 phoniebox systemd[1]: Listening on mpd.socket.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Sockets.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Basic System.
Dec  6 22:39:11 phoniebox systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
Dec  6 22:39:11 phoniebox systemd[1]: Started D-Bus System Message Bus.
Dec  6 22:39:11 phoniebox systemd[1]: Starting System Logging Service...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Check for Raspberry Pi EEPROM updates...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting triggerhappy global hotkey daemon...
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Dec  6 22:39:11 phoniebox systemd[1]: Starting WPA supplicant...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Deferred execution scheduler...
Dec  6 22:39:11 phoniebox systemd[1]: Starting rng-tools.service...
Dec  6 22:39:11 phoniebox systemd[1]: Starting Login Service...
Dec  6 22:39:11 phoniebox systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Dec  6 22:39:11 phoniebox systemd[1]: Starting dhcpcd on all interfaces...
Dec  6 22:39:11 phoniebox systemd[1]: Started Regular background program processing daemon.
Dec  6 22:39:11 phoniebox thd[360]: Found socket passed from systemd
Dec  6 22:39:11 phoniebox systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Dec  6 22:39:11 phoniebox systemd[1]: Starting LSB: Monitoring GPIO shutdown trigger...
Dec  6 22:39:11 phoniebox cron[390]: (CRON) INFO (pidfile fd = 3)
Dec  6 22:39:11 phoniebox systemd[1]: Started Manage Sound Card State (restore and store).
Dec  6 22:39:11 phoniebox rng-tools[373]: Starting Hardware RNG entropy gatherer daemon: rngd.
Dec  6 22:39:11 phoniebox rngd[408]: rngd 2-unofficial-mt.14 starting up...
Dec  6 22:39:11 phoniebox alsactl[400]: alsactl 1.1.8 daemon started
Dec  6 22:39:11 phoniebox cron[390]: (CRON) INFO (Running @reboot jobs)
Dec  6 22:39:11 phoniebox systemd[1]: Starting Save/Restore Sound Card State...
Dec  6 22:39:11 phoniebox rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Dec  6 22:39:11 phoniebox rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="356" x-info="https://www.rsyslog.com"] start
Dec  6 22:39:11 phoniebox systemd[1]: Reached target System Time Synchronized.
Dec  6 22:39:11 phoniebox dhcpcd[376]: dev: loaded udev
Dec  6 22:39:11 phoniebox rngd[408]: entropy feed to the kernel ready
Dec  6 22:39:11 phoniebox systemd[1]: Started Daily man-db regeneration.
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Successfully dropped root privileges.
Dec  6 22:39:11 phoniebox avahi-daemon[363]: avahi-daemon 0.7 starting up.
Dec  6 22:39:11 phoniebox systemd[1]: Started Daily rotation of log files.
Dec  6 22:39:11 phoniebox systemd[1]: Started Daily apt download activities.
Dec  6 22:39:11 phoniebox systemd[1]: Started Daily apt upgrade and clean activities.
Dec  6 22:39:11 phoniebox systemd[1]: Started Clean PHP session files every 30 mins.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Timers.
Dec  6 22:39:11 phoniebox systemd[1]: Started triggerhappy global hotkey daemon.
Dec  6 22:39:11 phoniebox kernel: [    9.681887] 8021q: 802.1Q VLAN Support v1.8
Dec  6 22:39:11 phoniebox systemd[1]: Started System Logging Service.
Dec  6 22:39:11 phoniebox dphys-swapfile[350]: want /var/swap=100MByte, checking existing: keeping it
Dec  6 22:39:11 phoniebox systemd[1]: Started Check for Raspberry Pi EEPROM updates.
Dec  6 22:39:11 phoniebox systemd[1]: Started Deferred execution scheduler.
Dec  6 22:39:11 phoniebox systemd[1]: Started rng-tools.service.
Dec  6 22:39:11 phoniebox systemd[1]: Started Save/Restore Sound Card State.
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Successfully called chroot().
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Successfully dropped remaining capabilities.
Dec  6 22:39:11 phoniebox avahi-daemon[363]: No service file found in /etc/avahi/services.
Dec  6 22:39:11 phoniebox wpa_supplicant[365]: Successfully initialized wpa_supplicant
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Network interface enumeration completed.
Dec  6 22:39:11 phoniebox avahi-daemon[363]: Server startup complete. Host name is phoniebox.local. Local service cookie is 310156994.
Dec  6 22:39:11 phoniebox dbus-daemon[351]: [system] Successfully activated service 'org.freedesktop.systemd1'
Dec  6 22:39:11 phoniebox kernel: [    9.845817] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
Dec  6 22:39:11 phoniebox systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
Dec  6 22:39:11 phoniebox cleanshutd[398]: Starting...
Dec  6 22:39:11 phoniebox systemd[1]: Started LSB: Monitoring GPIO shutdown trigger.
Dec  6 22:39:11 phoniebox systemd[1]: Reached target Sound Card.
Dec  6 22:39:11 phoniebox systemd[1]: Started WPA supplicant.
Dec  6 22:39:11 phoniebox systemd[1]: Started Login Service.
Dec  6 22:39:11 phoniebox systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Dec  6 22:39:11 phoniebox dhcpcd[376]: wlan0: starting wpa_supplicant
Dec  6 22:39:11 phoniebox dhcpcd-run-hooks[454]: wlan0: starting wpa_supplicant
Dec  6 22:39:12 phoniebox kernel: [   10.098768] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: connected to Access Point `'
Dec  6 22:39:12 phoniebox dhcpcd[376]: eth0: waiting for carrier
Dec  6 22:39:12 phoniebox kernel: [   10.514698] 8021q: adding VLAN 0 to HW filter on device eth0
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: waiting for carrier
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: carrier acquired
Dec  6 22:39:12 phoniebox dhcpcd[376]: DUID 00:01:00:01:26:60:d2:4f:b8:27:eb:d9:d6:f5
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: IAID eb:8c:83:a0
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: adding address fe80::6a74:6dc1:90f1:d2e
Dec  6 22:39:12 phoniebox avahi-daemon[363]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::6a74:6dc1:90f1:d2e.
Dec  6 22:39:12 phoniebox avahi-daemon[363]: New relevant interface wlan0.IPv6 for mDNS.
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: carrier lost
Dec  6 22:39:12 phoniebox avahi-daemon[363]: Registering new address record for fe80::6a74:6dc1:90f1:d2e on wlan0.*.
Dec  6 22:39:12 phoniebox dhcpcd[376]: wlan0: deleting address fe80::6a74:6dc1:90f1:d2e
Dec  6 22:39:12 phoniebox avahi-daemon[363]: Withdrawing address record for fe80::6a74:6dc1:90f1:d2e on wlan0.
Dec  6 22:39:12 phoniebox avahi-daemon[363]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::6a74:6dc1:90f1:d2e.
Dec  6 22:39:12 phoniebox avahi-daemon[363]: Interface wlan0.IPv6 no longer relevant for mDNS.
Dec  6 22:39:12 phoniebox raspi-config[393]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Dec  6 22:39:12 phoniebox systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Dec  6 22:39:14 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: carrier acquired
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: connected to Access Point `WLAN'
Dec  6 22:39:17 phoniebox kernel: [   15.531266] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: IAID eb:8c:83:a0
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: adding address fe80::70a1:2724:2efc:9a60
Dec  6 22:39:17 phoniebox avahi-daemon[363]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::70a1:2724:2efc:9a60.
Dec  6 22:39:17 phoniebox avahi-daemon[363]: New relevant interface wlan0.IPv6 for mDNS.
Dec  6 22:39:17 phoniebox avahi-daemon[363]: Registering new address record for fe80::70a1:2724:2efc:9a60 on wlan0.*.
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: soliciting an IPv6 router
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: rebinding lease of 10.0.0.24
Dec  6 22:39:17 phoniebox dhcpcd[376]: wlan0: probing address 10.0.0.24/24
Dec  6 22:39:18 phoniebox kernel: [   16.507336] Bluetooth: Core ver 2.22
Dec  6 22:39:18 phoniebox kernel: [   16.507438] NET: Registered protocol family 31
Dec  6 22:39:18 phoniebox kernel: [   16.507449] Bluetooth: HCI device and connection manager initialized
Dec  6 22:39:18 phoniebox kernel: [   16.507479] Bluetooth: HCI socket layer initialized
Dec  6 22:39:18 phoniebox kernel: [   16.507498] Bluetooth: L2CAP socket layer initialized
Dec  6 22:39:18 phoniebox kernel: [   16.507529] Bluetooth: SCO socket layer initialized
Dec  6 22:39:18 phoniebox kernel: [   16.524237] Bluetooth: HCI UART driver ver 2.3
Dec  6 22:39:18 phoniebox kernel: [   16.524256] Bluetooth: HCI UART protocol H4 registered
Dec  6 22:39:18 phoniebox kernel: [   16.524358] Bluetooth: HCI UART protocol Three-wire (H5) registered
Dec  6 22:39:18 phoniebox kernel: [   16.524632] Bluetooth: HCI UART protocol Broadcom registered
Dec  6 22:39:18 phoniebox btuart[359]: bcm43xx_init
Dec  6 22:39:18 phoniebox btuart[359]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd
Dec  6 22:39:18 phoniebox btuart[359]: Set BDADDR UART: b8:27:eb:73:7c:5f
Dec  6 22:39:18 phoniebox btuart[359]: Set Controller UART speed to 3000000 bit/s
Dec  6 22:39:18 phoniebox btuart[359]: Device setup complete
Dec  6 22:39:18 phoniebox systemd[1]: Starting Load/Save RF Kill Switch Status...
Dec  6 22:39:18 phoniebox systemd[1]: Started Configure Bluetooth Modems connected by UART.
Dec  6 22:39:18 phoniebox systemd[1]: Started Load/Save RF Kill Switch Status.
Dec  6 22:39:18 phoniebox systemd[1]: Created slice system-bthelper.slice.
Dec  6 22:39:18 phoniebox systemd[1]: Starting Bluetooth service...
Dec  6 22:39:18 phoniebox bluetoothd[500]: Bluetooth daemon 5.50
Dec  6 22:39:18 phoniebox systemd[1]: Started Bluetooth service.
Dec  6 22:39:18 phoniebox bluetoothd[500]: Starting SDP server
Dec  6 22:39:18 phoniebox systemd[1]: Started Raspberry Pi bluetooth helper.
Dec  6 22:39:18 phoniebox systemd[1]: Reached target Bluetooth.
Dec  6 22:39:18 phoniebox kernel: [   16.933474] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Dec  6 22:39:18 phoniebox kernel: [   16.933498] Bluetooth: BNEP filters: protocol multicast
Dec  6 22:39:18 phoniebox kernel: [   16.933529] Bluetooth: BNEP socket layer initialized
Dec  6 22:39:18 phoniebox bluetoothd[500]: Bluetooth management interface 1.14 initialized
Dec  6 22:39:18 phoniebox dbus-daemon[351]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=500 comm="/usr/lib/bluetooth/bluetoothd ")
Dec  6 22:39:18 phoniebox bluetoothd[500]: Sap driver initialization failed.
Dec  6 22:39:18 phoniebox bluetoothd[500]: sap-server: Operation not permitted (1)
Dec  6 22:39:18 phoniebox bthelper[501]: Raspberry Pi BDADDR already set
Dec  6 22:39:18 phoniebox systemd[1]: Starting Hostname Service...
Dec  6 22:39:19 phoniebox bluetoothd[500]: Failed to set privacy: Rejected (0x0b)
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F UUIDs: 00001801-0000-1000-8000-00805f9b34fb
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F UUIDs: 0000110e-0000-1000-8000-00805f9b34fb
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F UUIDs: 00001200-0000-1000-8000-00805f9b34fb
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F UUIDs: 0000110c-0000-1000-8000-00805f9b34fb
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F UUIDs: 00001800-0000-1000-8000-00805f9b34fb
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F Alias: BlueZ 5.50
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F Pairable: yes
Dec  6 22:39:19 phoniebox bthelper[501]: Changing power off succeeded
Dec  6 22:39:19 phoniebox dbus-daemon[351]: [system] Successfully activated service 'org.freedesktop.hostname1'
Dec  6 22:39:19 phoniebox systemd[1]: Started Hostname Service.
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F Name: phoniebox
Dec  6 22:39:19 phoniebox bthelper[501]: [#033[0;93mCHG#033[0m] Controller B8:27:EB:73:7C:5F Alias: BlueZ 5.50
Dec  6 22:39:19 phoniebox bthelper[501]: Changing power on succeeded
Dec  6 22:39:19 phoniebox systemd[1]: bthelper@hci0.service: Succeeded.
Dec  6 22:39:22 phoniebox dhcpcd[376]: wlan0: leased 10.0.0.24 for 86400 seconds
Dec  6 22:39:22 phoniebox avahi-daemon[363]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.24.
Dec  6 22:39:22 phoniebox avahi-daemon[363]: New relevant interface wlan0.IPv4 for mDNS.
Dec  6 22:39:22 phoniebox avahi-daemon[363]: Registering new address record for 10.0.0.24 on wlan0.IPv4.
Dec  6 22:39:22 phoniebox dhcpcd[376]: wlan0: adding route to 10.0.0.0/24
Dec  6 22:39:22 phoniebox dhcpcd[376]: wlan0: adding default route via 10.0.0.1
Dec  6 22:39:23 phoniebox dhcpcd[376]: forked to background, child pid 575
Dec  6 22:39:23 phoniebox systemd[1]: Started dhcpcd on all interfaces.
Dec  6 22:39:23 phoniebox systemd[1]: Reached target Network.
Dec  6 22:39:23 phoniebox systemd[1]: Started Phoniebox Idle Watchdog Service.
Dec  6 22:39:23 phoniebox systemd[1]: Starting OpenBSD Secure Shell server...
Dec  6 22:39:23 phoniebox systemd[1]: Starting Permit User Sessions...
Dec  6 22:39:23 phoniebox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Dec  6 22:39:23 phoniebox systemd[1]: Started Phoniebox RFID-Reader Service.
Dec  6 22:39:23 phoniebox systemd[1]: Started Phoniebox GPIO Control Service.
Dec  6 22:39:23 phoniebox systemd[1]: Starting Music Player Daemon...
Dec  6 22:39:23 phoniebox systemd[1]: Reached target Network is Online.
Dec  6 22:39:23 phoniebox systemd[1]: Starting LSB: exim Mail Transport Agent...
Dec  6 22:39:23 phoniebox systemd[1]: Starting Lighttpd Daemon...
Dec  6 22:39:23 phoniebox systemd[1]: Starting Samba NMB Daemon...
Dec  6 22:39:23 phoniebox systemd[1]: Starting /etc/rc.local Compatibility...
Dec  6 22:39:23 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Dec  6 22:39:23 phoniebox systemd[1]: Started Permit User Sessions.
Dec  6 22:39:23 phoniebox systemd[1]: Started /etc/rc.local Compatibility.
Dec  6 22:39:23 phoniebox systemd[1]: Started Getty on tty1.
Dec  6 22:39:23 phoniebox systemd[1]: Reached target Login Prompts.
Dec  6 22:39:23 phoniebox systemd[1]: Started OpenBSD Secure Shell server.
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:['VolumeControl', 'PrevNextControl', 'PlayPause', 'Shutdown', 'Volume0', 'VolumeUp', 'VolumeDown', 'NextSong', 'PrevSong', 'Halt']
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device VolumeControl not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:adding GPIO-Device, PrevNextControl
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:adding TwoButtonControl
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:GPIODevices.two_button_control:adding new action
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:GPIODevices.simple_button:{}: set when_pressed
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:GPIODevices.simple_button:add new action
Dec  6 22:39:25 phoniebox systemd[1]: Started Lighttpd Daemon.
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:GPIODevices.simple_button:{}: set when_pressed
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:GPIODevices.simple_button:add new action
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:adding GPIO-Device, PlayPause
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device Shutdown not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device Volume0 not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device VolumeUp not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device VolumeDown not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device NextSong not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device PrevSong not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Device Halt not enabled
Dec  6 22:39:25 phoniebox PhonieboxGPIOControl[583]: INFO:root:Ready for taking actions
Dec  6 22:39:25 phoniebox systemd[1]: Started Samba NMB Daemon.
Dec  6 22:39:25 phoniebox systemd[1]: Starting Samba SMB Daemon...
Dec  6 22:39:25 phoniebox exim4[585]: Starting MTA: exim4.
Dec  6 22:39:25 phoniebox systemd[1]: Started LSB: exim Mail Transport Agent.
Dec  6 22:39:25 phoniebox daemon_rfid_reader.py[582]: 2020-12-06 22:39:25,798 - root - INFO - Dir_PATH: /home/pi/RPi-Jukebox-RFID/scripts
Dec  6 22:39:26 phoniebox systemd[1]: Created slice User Slice of UID 1000.
Dec  6 22:39:26 phoniebox systemd[1]: Starting User Runtime Directory /run/user/1000...
Dec  6 22:39:26 phoniebox systemd[1]: Started User Runtime Directory /run/user/1000.
Dec  6 22:39:26 phoniebox systemd[1]: Starting User Manager for UID 1000...
Dec  6 22:39:26 phoniebox systemd[1]: Started Samba SMB Daemon.
Dec  6 22:39:26 phoniebox systemd[927]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Dec  6 22:39:26 phoniebox systemd[927]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Dec  6 22:39:26 phoniebox systemd[927]: Listening on GnuPG network certificate management daemon.
Dec  6 22:39:26 phoniebox systemd[927]: Reached target Paths.
Dec  6 22:39:26 phoniebox systemd[927]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Dec  6 22:39:26 phoniebox systemd[927]: Reached target Timers.
Dec  6 22:39:26 phoniebox systemd[927]: Listening on GnuPG cryptographic agent and passphrase cache.
Dec  6 22:39:27 phoniebox systemd[927]: Reached target Sockets.
Dec  6 22:39:27 phoniebox systemd[927]: Reached target Basic System.
Dec  6 22:39:27 phoniebox systemd[1]: Started User Manager for UID 1000.
Dec  6 22:39:27 phoniebox systemd[1]: Started Session 1 of user pi.
Dec  6 22:39:27 phoniebox systemd[927]: Starting Music Player Daemon...
Dec  6 22:39:28 phoniebox mpd: zeroconf: No global port, disabling zeroconf
Dec  6 22:39:28 phoniebox systemd[1]: Started Music Player Daemon.
Dec  6 22:39:28 phoniebox systemd[1]: Starting Phoniebox Startup...
Dec  6 22:39:28 phoniebox bash[976]: AUDIOFOLDERSPATH="/home/pi/RPi-Jukebox-RFID/shared/audiofolders"
Dec  6 22:39:28 phoniebox bash[976]: PLAYLISTSFOLDERPATH="/home/pi/RPi-Jukebox-RFID/playlists"
Dec  6 22:39:28 phoniebox bash[976]: SECONDSWIPE="RESTART"
Dec  6 22:39:28 phoniebox bash[976]: SECONDSWIPEPAUSE="2"
Dec  6 22:39:28 phoniebox bash[976]: SECONDSWIPEPAUSECONTROLS="ON"
Dec  6 22:39:28 phoniebox bash[976]: AUDIOIFACENAME="Headphone"
Dec  6 22:39:28 phoniebox bash[976]: VOLUMEMANAGER="mpd"
Dec  6 22:39:28 phoniebox bash[976]: AUDIOVOLCHANGESTEP="3"
Dec  6 22:39:28 phoniebox bash[976]: AUDIOVOLMAXLIMIT="100"
Dec  6 22:39:28 phoniebox bash[976]: AUDIOVOLMINLIMIT="1"
Dec  6 22:39:28 phoniebox bash[976]: AUDIOVOLSTARTUP="30"
Dec  6 22:39:28 phoniebox bash[976]: VOLCHANGEIDLE="TRUE"
Dec  6 22:39:28 phoniebox bash[976]: IDLETIMESHUTDOWN="15"
Dec  6 22:39:28 phoniebox bash[976]: POWEROFFCMD="sudo poweroff"
Dec  6 22:39:28 phoniebox bash[976]: SHOWCOVER="ON"
Dec  6 22:39:28 phoniebox bash[976]: READWLANIPYN="OFF"
Dec  6 22:39:28 phoniebox bash[976]: EDITION="classic"
Dec  6 22:39:28 phoniebox bash[976]: LANG="en-UK"
Dec  6 22:39:28 phoniebox bash[976]: VERSION="2.2 - 305325d - master"
Dec  6 22:39:28 phoniebox bash[976]: CMDVOLUP=""
Dec  6 22:39:28 phoniebox bash[976]: CMDVOLDOWN=""
Dec  6 22:39:28 phoniebox bash[976]: CMDNEXT=""
Dec  6 22:39:28 phoniebox bash[976]: CMDPREV=""
Dec  6 22:39:28 phoniebox bash[976]: CMDREWIND=""
Dec  6 22:39:28 phoniebox bash[976]: CMDSEEKFORW=""
Dec  6 22:39:28 phoniebox bash[976]: CMDSEEKBACK=""
Dec  6 22:39:28 phoniebox bash[976]: 30 is the mpd startup volume
Dec  6 22:39:28 phoniebox systemd[1]: Started Session 3 of user pi.
Dec  6 22:39:29 phoniebox mpd: exception: Failed to bind to '[::1]:6600'
Dec  6 22:39:29 phoniebox mpd: exception: nested: Failed to bind socket: Address already in use
Dec  6 22:39:29 phoniebox bash[976]: OK MPD 0.21.4
Dec  6 22:39:29 phoniebox bash[976]: OK
Dec  6 22:39:29 phoniebox bash[976]: 9830 is the mpg123 startup volume
Dec  6 22:39:29 phoniebox systemd[927]: mpd.service: Main process exited, code=exited, status=1/FAILURE
Dec  6 22:39:29 phoniebox systemd[927]: mpd.service: Failed with result 'exit-code'.
Dec  6 22:39:29 phoniebox systemd[927]: Failed to start Music Player Daemon.
Dec  6 22:39:29 phoniebox systemd[927]: Reached target Default.
Dec  6 22:39:29 phoniebox systemd[927]: Startup finished in 3.187s.
Dec  6 22:39:29 phoniebox bash[976]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
Dec  6 22:39:29 phoniebox bash[976]: #011version 1.25.10; written and copyright by Michael Hipp and others
Dec  6 22:39:29 phoniebox bash[976]: #011free software (LGPL) without any warranty but with best wishes
Dec  6 22:39:29 phoniebox bash[976]: Cannot connect to server socket err = No such file or directory
Dec  6 22:39:29 phoniebox bash[976]: Cannot connect to server request channel
Dec  6 22:39:29 phoniebox bash[976]: jack server is not running or cannot be started
Dec  6 22:39:29 phoniebox bash[976]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec  6 22:39:29 phoniebox bash[976]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec  6 22:39:29 phoniebox bash[976]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 42:  1093 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Dec  6 22:39:29 phoniebox bash[976]: Updating DB (#1) ...
Dec  6 22:39:29 phoniebox bash[976]: volume: 30%   repeat: off   random: off   single: off   consume: off
Dec  6 22:39:29 phoniebox mpd: update: updating 
[HERE A LOT OF UPDATES! REMOVED BY AUTHOR]
Dec  6 22:39:29 phoniebox systemd[1]: Started Phoniebox Startup.
Dec  6 22:39:29 phoniebox systemd[1]: Reached target Multi-User System.
Dec  6 22:39:29 phoniebox systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec  6 22:39:29 phoniebox systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Dec  6 22:39:29 phoniebox systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec  6 22:39:29 phoniebox systemd[1]: Startup finished in 3.496s (kernel) + 24.311s (userspace) = 27.808s.
Dec  6 22:39:31 phoniebox dhcpcd[575]: wlan0: no IPv6 Routers available
Dec  6 22:39:40 phoniebox systemd[1]: systemd-fsckd.service: Succeeded.
Dec  6 22:40:17 phoniebox systemd-timesyncd[308]: Synchronized to time server for the first time 79.133.44.136:123 (2.debian.pool.ntp.org).
Dec  6 22:40:25 phoniebox systemd[1]: systemd-hostnamed.service: Succeeded.
Dec  6 22:41:02 phoniebox idle-watchdog.sh[576]: warning: commands will be executed using /bin/sh
Dec  6 22:41:02 phoniebox idle-watchdog.sh[576]: job 63 at Sun Dec  6 22:56:00 2020

Just to clear things up: When I run the startup-script.sh from the commandline I get the same error if I am not running as root

s-martin commented 1 year ago

Closing as there was no activity for a very long time.

Please check the current release 2.4 or our new implementation 3.2, if this issue still persists.

Feel free to open a new issue, if necessary.

dvalob commented 6 months ago

Hi! I am having the same issue using the 2.5 version. I have also the segmentation fault when calling the startup script when trying to play the startup sound. Please find here my logs (volume is correctly set to 25 as per previous releases).

Mar  8 20:43:56 raspberrypi bash[913]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:  1032 **Segmentation fault**      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Mar  8 20:43:56 raspberrypi bash[913]: Updating DB (#1) ...
Mar  8 20:43:56 raspberrypi bash[913]: volume: 25%   repeat: off   random: off   single: off   consume: off_

Full log:

ar  8 20:43:42 raspberrypi fake-hwclock[116]: Fri  8 Mar 20:43:38 UTC 2024
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Booting Linux on physical CPU 0x0
Mar  8 20:43:42 raspberrypi systemd-fsck[135]: e2fsck 1.44.5 (15-Dec-2018)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Linux version 5.10.103-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1529 SMP Tue Mar 8 12:21:37 GMT 2022
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: div instructions available: patching division code
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] random: fast init done
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Memory policy: Data cache writealloc
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Reserved memory: created CMA memory pool at 0x37400000, size 64 MiB
Mar  8 20:43:42 raspberrypi systemd[1]: Started udev Coldplug all Devices.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Zone ranges:
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA      [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   Normal   empty
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Movable zone start for each node
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Early memory node ranges
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x000000003b3fffff]
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] On node 0 totalpages: 242688
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 2133 pages used for memmap
Mar  8 20:43:42 raspberrypi systemd[1]: Started Helper to synchronize boot up for ifupdown.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 0 pages reserved
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]   DMA zone: 242688 pages, LIFO batch:63
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 240555
Mar  8 20:43:42 raspberrypi systemd-fsck[135]: rootfs: clean, 114270/1797408 files, 943027/7748608 blocks
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=416 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  console=ttyS0,115200 console=tty1 root=PARTUUID=2ad10d96-02 rootfstype=ext4 fsck.repair=yes rootwait
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
Mar  8 20:43:42 raspberrypi systemd[1]: Started File System Check on Root Device.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] Memory: 878804K/970752K available (10240K kernel code, 1312K rwdata, 2952K rodata, 1024K init, 862K bss, 26412K reserved, 65536K cma-reserved)
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] ftrace: allocating 32081 entries in 95 pages
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] ftrace: allocated 94 pages with 5 groups
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] rcu: Hierarchical RCU implementation.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]  Rude variant of Tasks RCU enabled.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000]  Tracing variant of Tasks RCU enabled.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Mar  8 20:43:42 raspberrypi kernel: [    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000008] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000025] Switching to timer-based delay loop, resolution 52ns
Mar  8 20:43:42 raspberrypi kernel: [    0.000310] Console: colour dummy device 80x30
Mar  8 20:43:42 raspberrypi kernel: [    0.001134] printk: console [tty1] enabled
Mar  8 20:43:42 raspberrypi kernel: [    0.001202] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Mar  8 20:43:42 raspberrypi kernel: [    0.001260] pid_max: default: 32768 minimum: 301
Mar  8 20:43:42 raspberrypi kernel: [    0.001482] LSM: Security Framework initializing
Mar  8 20:43:42 raspberrypi kernel: [    0.001745] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.001795] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.003267] cgroup: Disabling memory control group subsystem
Mar  8 20:43:42 raspberrypi kernel: [    0.003553] CPU: Testing write buffer coherency: ok
Mar  8 20:43:42 raspberrypi kernel: [    0.004066] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Mar  8 20:43:42 raspberrypi kernel: [    0.005319] Setting up static identity map for 0x100000 - 0x10003c
Mar  8 20:43:42 raspberrypi kernel: [    0.005527] rcu: Hierarchical SRCU implementation.
Mar  8 20:43:42 raspberrypi kernel: [    0.006466] smp: Bringing up secondary CPUs ...
Mar  8 20:43:42 raspberrypi kernel: [    0.007649] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Mar  8 20:43:42 raspberrypi kernel: [    0.008926] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Mar  8 20:43:42 raspberrypi kernel: [    0.010248] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Mar  8 20:43:42 raspberrypi kernel: [    0.010407] smp: Brought up 1 node, 4 CPUs
Mar  8 20:43:42 raspberrypi kernel: [    0.010461] SMP: Total of 4 processors activated (153.60 BogoMIPS).
Mar  8 20:43:42 raspberrypi kernel: [    0.010492] CPU: All CPU(s) started in HYP mode.
Mar  8 20:43:42 raspberrypi kernel: [    0.010519] CPU: Virtualization extensions available.
Mar  8 20:43:42 raspberrypi kernel: [    0.011536] devtmpfs: initialized
Mar  8 20:43:42 raspberrypi kernel: [    0.029067] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Mar  8 20:43:42 raspberrypi kernel: [    0.029365] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Mar  8 20:43:42 raspberrypi kernel: [    0.029426] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    0.032449] pinctrl core: initialized pinctrl subsystem
Mar  8 20:43:42 raspberrypi kernel: [    0.033678] NET: Registered protocol family 16
Mar  8 20:43:42 raspberrypi kernel: [    0.037908] DMA: preallocated 1024 KiB pool for atomic coherent allocations
Mar  8 20:43:42 raspberrypi kernel: [    0.043629] audit: initializing netlink subsys (disabled)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Remount Root and Kernel File Systems...
Mar  8 20:43:42 raspberrypi kernel: [    0.044551] thermal_sys: Registered thermal governor 'step_wise'
Mar  8 20:43:42 raspberrypi kernel: [    0.045384] audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
Mar  8 20:43:42 raspberrypi kernel: [    0.045612] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Mar  8 20:43:42 raspberrypi kernel: [    0.045653] hw-breakpoint: maximum watchpoint size is 8 bytes.
Mar  8 20:43:42 raspberrypi kernel: [    0.045973] Serial: AMBA PL011 UART driver
Mar  8 20:43:42 raspberrypi kernel: [    0.064336] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Mar  8 20:43:42 raspberrypi systemd[1]: Started Set the console keyboard layout.
Mar  8 20:43:42 raspberrypi kernel: [    0.080149] raspberrypi-firmware soc:firmware: Attached to firmware from 2023-05-09T12:17:54, variant start
Mar  8 20:43:42 raspberrypi kernel: [    0.090161] raspberrypi-firmware soc:firmware: Firmware hash is 30aa0d70ab280427ba04ebc718c81d4350b9d394
Mar  8 20:43:42 raspberrypi kernel: [    0.134714] Kprobes globally optimized
Mar  8 20:43:42 raspberrypi kernel: [    0.139985] bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Mar  8 20:43:42 raspberrypi systemd[1]: Started Remount Root and Kernel File Systems.
Mar  8 20:43:42 raspberrypi kernel: [    0.142369] SCSI subsystem initialized
Mar  8 20:43:42 raspberrypi kernel: [    0.142652] usbcore: registered new interface driver usbfs
Mar  8 20:43:42 raspberrypi kernel: [    0.142738] usbcore: registered new interface driver hub
Mar  8 20:43:42 raspberrypi kernel: [    0.142831] usbcore: registered new device driver usb
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Flush Journal to Persistent Storage...
Mar  8 20:43:42 raspberrypi kernel: [    0.144809] clocksource: Switched to clocksource arch_sys_counter
Mar  8 20:43:42 raspberrypi kernel: [    1.862463] VFS: Disk quotas dquot_6.6.0
Mar  8 20:43:42 raspberrypi kernel: [    1.862609] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    1.862834] FS-Cache: Loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.863089] CacheFiles: Loaded
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Mar  8 20:43:42 raspberrypi kernel: [    1.874252] NET: Registered protocol family 2
Mar  8 20:43:42 raspberrypi kernel: [    1.874565] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.876565] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.876658] TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create System Users...
Mar  8 20:43:42 raspberrypi kernel: [    1.876803] TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877016] TCP: Hash tables configured (established 8192 bind 8192)
Mar  8 20:43:42 raspberrypi kernel: [    1.877220] UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877298] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Mar  8 20:43:42 raspberrypi kernel: [    1.877578] NET: Registered protocol family 1
Mar  8 20:43:42 raspberrypi kernel: [    1.878668] RPC: Registered named UNIX socket transport module.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Load/Save Random Seed...
Mar  8 20:43:42 raspberrypi kernel: [    1.878706] RPC: Registered udp transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.878735] RPC: Registered tcp transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.878764] RPC: Registered tcp NFSv4.1 backchannel transport module.
Mar  8 20:43:42 raspberrypi kernel: [    1.880536] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Mar  8 20:43:42 raspberrypi systemd[1]: Started Load/Save Random Seed.
Mar  8 20:43:42 raspberrypi kernel: [    1.884280] Initialise system trusted keyrings
Mar  8 20:43:42 raspberrypi kernel: [    1.884584] workingset: timestamp_bits=14 max_order=18 bucket_order=4
Mar  8 20:43:42 raspberrypi kernel: [    1.894157] zbud: loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.896321] FS-Cache: Netfs 'nfs' registered for caching
Mar  8 20:43:42 raspberrypi kernel: [    1.897240] NFS: Registering the id_resolver key type
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create System Users.
Mar  8 20:43:42 raspberrypi kernel: [    1.897314] Key type id_resolver registered
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create Static Device Nodes in /dev...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Flush Journal to Persistent Storage.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create Static Device Nodes in /dev.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Local File Systems (Pre).
Mar  8 20:43:42 raspberrypi systemd[1]: Starting udev Kernel Device Manager...
Mar  8 20:43:42 raspberrypi systemd[1]: Started udev Kernel Device Manager.
Mar  8 20:43:42 raspberrypi mtp-probe: checking bus 1, device 4: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4"
Mar  8 20:43:42 raspberrypi mtp-probe: bus: 1, device: 4 was not an MTP device
Mar  8 20:43:42 raspberrypi mtp-probe: checking bus 1, device 3: "/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1"
Mar  8 20:43:42 raspberrypi mtp-probe: bus: 1, device: 3 was not an MTP device
Mar  8 20:43:42 raspberrypi kernel: [    1.897344] Key type id_legacy registered
Mar  8 20:43:42 raspberrypi systemd[1]: Found device /dev/disk/by-partuuid/2ad10d96-01.
Mar  8 20:43:42 raspberrypi kernel: [    1.897528] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Mar  8 20:43:42 raspberrypi kernel: [    1.897565] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Mar  8 20:43:42 raspberrypi kernel: [    1.898814] Key type asymmetric registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898848] Asymmetric key parser 'x509' registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898920] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting File System Check on /dev/disk/by-partuuid/2ad10d96-01...
Mar  8 20:43:42 raspberrypi kernel: [    1.898961] io scheduler mq-deadline registered
Mar  8 20:43:42 raspberrypi kernel: [    1.898990] io scheduler kyber registered
Mar  8 20:43:42 raspberrypi kernel: [    1.904008] bcm2708_fb soc:fb: FB found 1 display(s)
Mar  8 20:43:42 raspberrypi kernel: [    1.915754] Console: switching to colour frame buffer device 82x26
Mar  8 20:43:42 raspberrypi systemd-udevd[173]: Using default interface naming scheme 'v240'.
Mar  8 20:43:42 raspberrypi kernel: [    1.923025] bcm2708_fb soc:fb: Registered framebuffer for display 0, size 656x416
Mar  8 20:43:42 raspberrypi kernel: [    1.933722] bcm2835-rng 3f104000.rng: hwrng registered
Mar  8 20:43:42 raspberrypi kernel: [    1.936798] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Mar  8 20:43:42 raspberrypi kernel: [    1.942723] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Mar  8 20:43:42 raspberrypi systemd-udevd[180]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Mar  8 20:43:42 raspberrypi systemd-udevd[178]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Mar  8 20:43:42 raspberrypi systemd-fsck[276]: fsck.fat 4.1 (2017-01-24)
Mar  8 20:43:42 raspberrypi systemd-fsck[276]: /dev/mmcblk0p1: 289 files, 98789/516190 clusters
Mar  8 20:43:42 raspberrypi systemd[1]: Started File System Check on /dev/disk/by-partuuid/2ad10d96-01.
Mar  8 20:43:42 raspberrypi systemd-udevd[172]: Using default interface naming scheme 'v240'.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Mounting /boot...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Load/Save RF Kill Switch Status...
Mar  8 20:43:42 raspberrypi systemd[1]: Mounted /boot.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Local File Systems.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Preprocess NFS configuration...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Set console font and keymap...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Raise network interfaces...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Create Volatile Files and Directories...
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Load/Save RF Kill Switch Status.
Mar  8 20:43:42 raspberrypi systemd[1]: nfs-config.service: Succeeded.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Preprocess NFS configuration.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Set console font and keymap.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target NFS client services.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Remote File Systems (Pre).
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Remote File Systems.
Mar  8 20:43:42 raspberrypi kernel: [    1.960361] brd: module loaded
Mar  8 20:43:42 raspberrypi systemd[1]: Started Create Volatile Files and Directories.
Mar  8 20:43:42 raspberrypi kernel: [    1.975318] loop: module loaded
Mar  8 20:43:42 raspberrypi kernel: [    1.979913] Loading iSCSI transport class v2.0-870.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Network Time Synchronization...
Mar  8 20:43:42 raspberrypi kernel: [    1.984429] usbcore: registered new interface driver lan78xx
Mar  8 20:43:42 raspberrypi kernel: [    1.987238] usbcore: registered new interface driver smsc95xx
Mar  8 20:43:42 raspberrypi kernel: [    1.989790] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Mar  8 20:43:42 raspberrypi kernel: [    2.720656] Core Release: 2.80a
Mar  8 20:43:42 raspberrypi kernel: [    2.723208] Setting default values for core params
Mar  8 20:43:42 raspberrypi kernel: [    2.725770] Finished setting default values for core params
Mar  8 20:43:42 raspberrypi kernel: [    2.928597] Using Buffer DMA mode
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Mar  8 20:43:42 raspberrypi kernel: [    2.931034] Periodic Transfer Interrupt Enhancement - disabled
Mar  8 20:43:42 raspberrypi kernel: [    2.933588] Multiprocessor Interrupt Enhancement - disabled
Mar  8 20:43:42 raspberrypi kernel: [    2.936173] OTG VER PARAM: 0, OTG VER FLAG: 0
Mar  8 20:43:42 raspberrypi kernel: [    2.938701] Dedicated Tx FIFOs mode
Mar  8 20:43:42 raspberrypi systemd[1]: Started Update UTMP about System Boot/Shutdown.
Mar  8 20:43:42 raspberrypi kernel: [    2.941735] 
Mar  8 20:43:42 raspberrypi kernel: [    2.941755] WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = b7504000 dma = 0xf7504000 len=9024
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] FIQ FSM acceleration enabled for :
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Non-periodic Split Transactions
Mar  8 20:43:42 raspberrypi systemd[1]: Started Raise network interfaces.
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Periodic Split Transactions
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] High-Speed Isochronous Endpoints
Mar  8 20:43:42 raspberrypi kernel: [    2.948879] Interrupt/Control Split Transaction hack enabled
Mar  8 20:43:42 raspberrypi kernel: [    2.959857] dwc_otg: Microframe scheduler enabled
Mar  8 20:43:42 raspberrypi systemd[1]: Started Network Time Synchronization.
Mar  8 20:43:42 raspberrypi kernel: [    2.959920] 
Mar  8 20:43:42 raspberrypi kernel: [    2.959929] WARN::hcd_init_fiq:457: FIQ on core 1
Mar  8 20:43:42 raspberrypi kernel: [    2.964237] 
Mar  8 20:43:42 raspberrypi kernel: [    2.964246] WARN::hcd_init_fiq:458: FIQ ASM at 807cb8b8 length 36
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target System Time Synchronized.
Mar  8 20:43:42 raspberrypi kernel: [    2.968678] 
Mar  8 20:43:42 raspberrypi kernel: [    2.968686] WARN::hcd_init_fiq:497: MPHI regs_base at bb810000
Mar  8 20:43:42 raspberrypi kernel: [    2.973164] dwc_otg 3f980000.usb: DWC OTG Controller
Mar  8 20:43:42 raspberrypi kernel: [    2.975498] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target System Initialization.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on mpd.socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on triggerhappy.socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily Cleanup of Temporary Directories.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily man-db regeneration.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on D-Bus System Message Bus Socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Sockets.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Basic System.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Manage Sound Card State (restore and store).
Mar  8 20:43:42 raspberrypi systemd[1]: Starting triggerhappy global hotkey daemon...
Mar  8 20:43:42 raspberrypi alsactl[385]: alsactl 1.1.8 daemon started
Mar  8 20:43:42 raspberrypi systemd[1]: Starting LSB: Switch to ondemand cpu governor (unless shift key is pressed)...
Mar  8 20:43:42 raspberrypi kernel: [    2.977875] dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
Mar  8 20:43:42 raspberrypi kernel: [    2.980197] Init: Port Power? op_state=1
Mar  8 20:43:42 raspberrypi kernel: [    2.982429] Init: Power Port (0)
Mar  8 20:43:42 raspberrypi kernel: [    2.984955] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Mar  8 20:43:42 raspberrypi kernel: [    2.989559] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Mar  8 20:43:42 raspberrypi kernel: [    2.992026] usb usb1: Product: DWC OTG Controller
Mar  8 20:43:42 raspberrypi kernel: [    2.994390] usb usb1: Manufacturer: Linux 5.10.103-v7+ dwc_otg_hcd
Mar  8 20:43:42 raspberrypi kernel: [    2.996812] usb usb1: SerialNumber: 3f980000.usb
Mar  8 20:43:42 raspberrypi kernel: [    2.999951] hub 1-0:1.0: USB hub found
Mar  8 20:43:42 raspberrypi kernel: [    3.002395] hub 1-0:1.0: 1 port detected
Mar  8 20:43:42 raspberrypi kernel: [    3.006190] dwc_otg: FIQ enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006204] dwc_otg: NAK holdoff enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006215] dwc_otg: FIQ split-transaction FSM enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.006233] Module dwc_common_port init
Mar  8 20:43:42 raspberrypi kernel: [    3.006577] usbcore: registered new interface driver usb-storage
Mar  8 20:43:42 raspberrypi kernel: [    3.009275] mousedev: PS/2 mouse device common for all mice
Mar  8 20:43:42 raspberrypi kernel: [    3.012884] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Mar  8 20:43:42 raspberrypi kernel: [    3.017870] sdhci: Secure Digital Host Controller Interface driver
Mar  8 20:43:42 raspberrypi kernel: [    3.020413] sdhci: Copyright(c) Pierre Ossman
Mar  8 20:43:42 raspberrypi kernel: [    3.023499] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Mar  8 20:43:42 raspberrypi kernel: [    3.026959] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Mar  8 20:43:42 raspberrypi kernel: [    3.029780] sdhci-pltfm: SDHCI platform and OF driver helper
Mar  8 20:43:42 raspberrypi kernel: [    3.034260] ledtrig-cpu: registered to indicate activity on CPUs
Mar  8 20:43:42 raspberrypi kernel: [    3.037379] hid: raw HID events driver (C) Jiri Kosina
Mar  8 20:43:42 raspberrypi kernel: [    3.040194] usbcore: registered new interface driver usbhid
Mar  8 20:43:42 raspberrypi kernel: [    3.042808] usbhid: USB HID core driver
Mar  8 20:43:42 raspberrypi kernel: [    3.050581] Initializing XFRM netlink socket
Mar  8 20:43:42 raspberrypi kernel: [    3.053210] NET: Registered protocol family 17
Mar  8 20:43:42 raspberrypi kernel: [    3.055891] Key type dns_resolver registered
Mar  8 20:43:42 raspberrypi kernel: [    3.058887] Registering SWP/SWPB emulation handler
Mar  8 20:43:42 raspberrypi kernel: [    3.061396] registered taskstats version 1
Mar  8 20:43:42 raspberrypi kernel: [    3.063740] Loading compiled-in X.509 certificates
Mar  8 20:43:42 raspberrypi kernel: [    3.066937] Key type ._fscrypt registered
Mar  8 20:43:42 raspberrypi kernel: [    3.069269] Key type .fscrypt registered
Mar  8 20:43:42 raspberrypi kernel: [    3.071589] Key type fscrypt-provisioning registered
Mar  8 20:43:42 raspberrypi kernel: [    3.085412] uart-pl011 3f201000.serial: cts_event_workaround enabled
Mar  8 20:43:42 raspberrypi kernel: [    3.087903] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
Mar  8 20:43:42 raspberrypi kernel: [    3.095009] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Mar  8 20:43:42 raspberrypi kernel: [    3.099182] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Mar  8 20:43:42 raspberrypi kernel: [    3.101654] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Mar  8 20:43:42 raspberrypi kernel: [    3.130959] sdhost: log_buf @ (ptrval) (f7507000)
Mar  8 20:43:42 raspberrypi kernel: [    3.134997] Indeed it is in host mode hprt0 = 00021501
Mar  8 20:43:42 raspberrypi kernel: [    3.232812] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.236873] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.240806] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.245135] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Mar  8 20:43:42 raspberrypi kernel: [    3.250276] of_cfs_init
Mar  8 20:43:42 raspberrypi kernel: [    3.252642] of_cfs_init: OK
Mar  8 20:43:42 raspberrypi kernel: [    3.255924] Waiting for root device PARTUUID=2ad10d96-02...
Mar  8 20:43:42 raspberrypi kernel: [    3.268840] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Mar  8 20:43:42 raspberrypi kernel: [    3.307997] mmc0: host does not support reading read-only switch, assuming write-enable
Mar  8 20:43:42 raspberrypi kernel: [    3.315671] mmc0: new high speed SDHC card at address 0001
Mar  8 20:43:42 raspberrypi kernel: [    3.319225] mmcblk0: mmc0:0001 EB1QT 29.8 GiB
Mar  8 20:43:42 raspberrypi kernel: [    3.324538]  mmcblk0: p1 p2
Mar  8 20:43:42 raspberrypi kernel: [    3.358726] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Mar  8 20:43:42 raspberrypi kernel: [    3.363460] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
Mar  8 20:43:42 raspberrypi kernel: [    3.374270] devtmpfs: mounted
Mar  8 20:43:42 raspberrypi kernel: [    3.374867] usb 1-1: new high-speed USB device number 2 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    3.379154] Indeed it is in host mode hprt0 = 00001101
Mar  8 20:43:42 raspberrypi kernel: [    3.443352] Freeing unused kernel memory: 1024K
Mar  8 20:43:42 raspberrypi kernel: [    3.450418] mmc1: new high speed SDIO card at address 0001
Mar  8 20:43:42 raspberrypi kernel: [    3.465333] Run /sbin/init as init process
Mar  8 20:43:42 raspberrypi kernel: [    3.467801]   with arguments:
Mar  8 20:43:42 raspberrypi kernel: [    3.467813]     /sbin/init
Mar  8 20:43:42 raspberrypi kernel: [    3.467824]   with environment:
Mar  8 20:43:42 raspberrypi kernel: [    3.467835]     HOME=/
Mar  8 20:43:42 raspberrypi kernel: [    3.467846]     TERM=linux
Mar  8 20:43:42 raspberrypi kernel: [    3.615246] usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
Mar  8 20:43:42 raspberrypi kernel: [    3.620631] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  8 20:43:42 raspberrypi kernel: [    3.624295] hub 1-1:1.0: USB hub found
Mar  8 20:43:42 raspberrypi kernel: [    3.627379] hub 1-1:1.0: 5 ports detected
Mar  8 20:43:42 raspberrypi kernel: [    3.944871] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    4.075286] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Mar  8 20:43:42 raspberrypi kernel: [    4.080925] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Mar  8 20:43:42 raspberrypi kernel: [    4.086759] smsc95xx v2.0.0
Mar  8 20:43:42 raspberrypi kernel: [    4.208627] NET: Registered protocol family 10
Mar  8 20:43:42 raspberrypi kernel: [    4.213007] Segment Routing with IPv6
Mar  8 20:43:42 raspberrypi kernel: [    4.214669] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:b9:f7:e2
Mar  8 20:43:42 raspberrypi kernel: [    4.314882] usb 1-1.4: new full-speed USB device number 4 using dwc_otg
Mar  8 20:43:42 raspberrypi kernel: [    4.462561] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=dd01, bcdDevice= 0.00
Mar  8 20:43:42 raspberrypi kernel: [    4.474463] usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Mar  8 20:43:42 raspberrypi kernel: [    4.489294] usb 1-1.4: Product: RFID Reader
Mar  8 20:43:42 raspberrypi kernel: [    4.492626] usb 1-1.4: Manufacturer: RFID Reader
Mar  8 20:43:42 raspberrypi kernel: [    4.495773] usb 1-1.4: SerialNumber: ID READER
Mar  8 20:43:42 raspberrypi kernel: [    4.523209] input: RFID Reader RFID Reader as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:1A86:DD01.0001/input/input0
Mar  8 20:43:42 raspberrypi kernel: [    4.535190] input: RFID Reader RFID Reader Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/0003:1A86:DD01.0001/input/input1
Mar  8 20:43:42 raspberrypi kernel: [    4.606067] hid-generic 0003:1A86:DD01.0001: input,hidraw0: USB HID v1.10 Device [RFID Reader RFID Reader] on usb-3f980000.usb-1.4/input0
Mar  8 20:43:42 raspberrypi kernel: [    5.390789] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    5.414418] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    5.428932] random: systemd: uninitialized urandom read (16 bytes read)
Mar  8 20:43:42 raspberrypi kernel: [    6.345370] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
Mar  8 20:43:42 raspberrypi kernel: [    7.101050] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.103935] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Mar  8 20:43:42 raspberrypi kernel: [    7.103964] [vc_sm_connected_init]: start
Mar  8 20:43:42 raspberrypi kernel: [    7.112152] mc: Linux media interface: v0.10
Mar  8 20:43:42 raspberrypi kernel: [    7.115596] [vc_sm_connected_init]: installed successfully
Mar  8 20:43:42 raspberrypi kernel: [    7.182543] videodev: Linux video capture interface: v2.00
Mar  8 20:43:42 raspberrypi kernel: [    7.216049] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.228299] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.246250] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.258584] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.269129] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.288115] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.291066] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Mar  8 20:43:42 raspberrypi kernel: [    7.308571] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Mar  8 20:43:42 raspberrypi kernel: [    7.308637] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Mar  8 20:43:42 raspberrypi kernel: [    7.319900] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Mar  8 20:43:42 raspberrypi kernel: [    7.320078] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Mar  8 20:43:42 raspberrypi kernel: [    7.328674] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Mar  8 20:43:42 raspberrypi kernel: [    7.329366] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Mar  8 20:43:42 raspberrypi kernel: [    7.329844] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Mar  8 20:43:42 raspberrypi kernel: [    7.330035] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Mar  8 20:43:42 raspberrypi kernel: [    7.330104] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Mar  8 20:43:42 raspberrypi kernel: [    7.330316] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Mar  8 20:43:42 raspberrypi kernel: [    7.330363] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330422] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330455] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330483] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Mar  8 20:43:42 raspberrypi kernel: [    7.330962] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Mar  8 20:43:42 raspberrypi kernel: [    7.331153] bcm2835_audio bcm2835_audio: card created with 8 channels
Mar  8 20:43:42 raspberrypi kernel: [    7.346559] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
Mar  8 20:43:42 raspberrypi kernel: [    7.346644] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
Mar  8 20:43:42 raspberrypi kernel: [    7.607963] cfg80211: Loading compiled-in X.509 certificates for regulatory database
Mar  8 20:43:42 raspberrypi kernel: [    7.728942] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Mar  8 20:43:42 raspberrypi kernel: [    7.757695] cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
Mar  8 20:43:42 raspberrypi kernel: [    7.892045] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Mar  8 20:43:42 raspberrypi kernel: [    7.919389] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Mar  8 20:43:42 raspberrypi kernel: [    7.920235] usbcore: registered new interface driver brcmfmac
Mar  8 20:43:42 raspberrypi kernel: [    8.211451] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Mar  8 20:43:42 raspberrypi kernel: [    8.227376] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
Mar  8 20:43:42 raspberrypi kernel: [    9.444950] Under-voltage detected! (0x00050005)
Mar  8 20:43:42 raspberrypi kernel: [    9.721092] random: crng init done
Mar  8 20:43:42 raspberrypi kernel: [    9.721112] random: 7 urandom warning(s) missed due to ratelimiting
Mar  8 20:43:42 raspberrypi systemd[1]: Starting System Logging Service...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Regular background program processing daemon.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting rng-tools.service...
Mar  8 20:43:42 raspberrypi thd[386]: Found socket passed from systemd
Mar  8 20:43:42 raspberrypi thd[386]: Device /dev/input/event0 not suitable.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Check for Raspberry Pi EEPROM updates...
Mar  8 20:43:42 raspberrypi cron[389]: (CRON) INFO (pidfile fd = 3)
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Deferred execution scheduler...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting dhcpcd on all interfaces...
Mar  8 20:43:42 raspberrypi rng-tools[391]: Starting Hardware RNG entropy gatherer daemon: rngd.
Mar  8 20:43:42 raspberrypi rngd[399]: rngd 2-unofficial-mt.14 starting up...
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting dphys-swapfile - set up, mount/unmount, and delete a swap file...
Mar  8 20:43:42 raspberrypi cron[389]: (CRON) INFO (Running @reboot jobs)
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily apt download activities.
Mar  8 20:43:42 raspberrypi rngd[399]: entropy feed to the kernel ready
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Login Service...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Clean PHP session files every 30 mins.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Mar  8 20:43:42 raspberrypi systemd[1]: Started D-Bus System Message Bus.
Mar  8 20:43:42 raspberrypi rpi-eeprom-update[392]: This tool only works with a Raspberry Pi 4
Mar  8 20:43:42 raspberrypi systemd[1]: Starting WPA supplicant...
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Save/Restore Sound Card State...
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily apt upgrade and clean activities.
Mar  8 20:43:42 raspberrypi systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Daily rotation of log files.
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Timers.
Mar  8 20:43:42 raspberrypi dhcpcd[397]: dev: loaded udev
Mar  8 20:43:42 raspberrypi rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.1901.0]
Mar  8 20:43:42 raspberrypi rsyslogd:  [origin software="rsyslogd" swVersion="8.1901.0" x-pid="388" x-info="https://www.rsyslog.com"] start
Mar  8 20:43:42 raspberrypi systemd[1]: Started triggerhappy global hotkey daemon.
Mar  8 20:43:42 raspberrypi systemd[1]: Started System Logging Service.
Mar  8 20:43:42 raspberrypi systemd[1]: Started rng-tools.service.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Check for Raspberry Pi EEPROM updates.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Found user 'avahi' (UID 108) and group 'avahi' (GID 113).
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully dropped root privileges.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: avahi-daemon 0.7 starting up.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Deferred execution scheduler.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Save/Restore Sound Card State.
Mar  8 20:43:42 raspberrypi kernel: [    9.971174] 8021q: 802.1Q VLAN Support v1.8
Mar  8 20:43:42 raspberrypi systemd[1]: Reached target Sound Card.
Mar  8 20:43:42 raspberrypi dphys-swapfile[403]: want /var/swap=100MByte, checking existing: keeping it
Mar  8 20:43:42 raspberrypi kernel: [   10.106609] uart-pl011 3f201000.serial: no DMA platform data
Mar  8 20:43:42 raspberrypi btuart[414]: Cannot open directory '/etc/firmware': No such file or directory
Mar  8 20:43:42 raspberrypi btuart[414]: Patch not found, continue anyway
Mar  8 20:43:42 raspberrypi raspi-config[387]: Checking if shift key is held down:Device /dev/input/event0 not suitable.
Mar  8 20:43:42 raspberrypi dbus-daemon[416]: [system] Successfully activated service 'org.freedesktop.systemd1'
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully called chroot().
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Successfully dropped remaining capabilities.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: No service file found in /etc/avahi/services.
Mar  8 20:43:42 raspberrypi systemd[1]: Started Login Service.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Network interface enumeration completed.
Mar  8 20:43:42 raspberrypi avahi-daemon[404]: Server startup complete. Host name is raspberrypi.local. Local service cookie is 1775547982.
Mar  8 20:43:42 raspberrypi wpa_supplicant[422]: Successfully initialized wpa_supplicant
Mar  8 20:43:42 raspberrypi systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Mar  8 20:43:42 raspberrypi systemd[1]: Started WPA supplicant.
Mar  8 20:43:42 raspberrypi kernel: [   10.237762] Bluetooth: Core ver 2.22
Mar  8 20:43:42 raspberrypi kernel: [   10.237915] NET: Registered protocol family 31
Mar  8 20:43:42 raspberrypi kernel: [   10.237928] Bluetooth: HCI device and connection manager initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238668] Bluetooth: HCI socket layer initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238692] Bluetooth: L2CAP socket layer initialized
Mar  8 20:43:42 raspberrypi kernel: [   10.238730] Bluetooth: SCO socket layer initialized
Mar  8 20:43:42 raspberrypi dhcpcd[397]: wlan0: starting wpa_supplicant
Mar  8 20:43:42 raspberrypi btuart[414]: bcm43xx_init
Mar  8 20:43:42 raspberrypi btuart[414]: Set BDADDR UART: b8:27:eb:13:5d:48
Mar  8 20:43:42 raspberrypi btuart[414]: Set Controller UART speed to 921600 bit/s
Mar  8 20:43:42 raspberrypi btuart[414]: Device setup complete
Mar  8 20:43:42 raspberrypi systemd[1]: Started Configure Bluetooth Modems connected by UART.
Mar  8 20:43:42 raspberrypi kernel: [   10.265283] Bluetooth: HCI UART driver ver 2.3
Mar  8 20:43:42 raspberrypi kernel: [   10.265311] Bluetooth: HCI UART protocol H4 registered
Mar  8 20:43:42 raspberrypi kernel: [   10.265453] Bluetooth: HCI UART protocol Three-wire (H5) registered
Mar  8 20:43:42 raspberrypi kernel: [   10.265755] Bluetooth: HCI UART protocol Broadcom registered
Mar  8 20:43:42 raspberrypi dhcpcd-run-hooks[484]: wlan0: starting wpa_supplicant
Mar  8 20:43:42 raspberrypi kernel: [   10.295083] Adding 102396k swap on /var/swap.  Priority:-2 extents:1 across:102396k SSFS
Mar  8 20:43:42 raspberrypi systemd[1]: Started dphys-swapfile - set up, mount/unmount, and delete a swap file.
Mar  8 20:43:42 raspberrypi systemd[1]: Created slice system-bthelper.slice.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Raspberry Pi bluetooth helper...
Mar  8 20:43:42 raspberrypi kernel: [   10.462620] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
Mar  8 20:43:42 raspberrypi bthelper[489]: Raspberry Pi BDADDR already set
Mar  8 20:43:42 raspberrypi systemd[1]: Started Raspberry Pi bluetooth helper.
Mar  8 20:43:42 raspberrypi systemd[1]: Starting Bluetooth service...
Mar  8 20:43:42 raspberrypi bluetoothd[499]: Bluetooth daemon 5.50
Mar  8 20:43:43 raspberrypi systemd[1]: Started Bluetooth service.
Mar  8 20:43:43 raspberrypi systemd[1]: Reached target Bluetooth.
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Starting SDP server
Mar  8 20:43:43 raspberrypi kernel: [   10.728045] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Mar  8 20:43:43 raspberrypi kernel: [   10.728069] Bluetooth: BNEP filters: protocol multicast
Mar  8 20:43:43 raspberrypi kernel: [   10.728099] Bluetooth: BNEP socket layer initialized
Mar  8 20:43:43 raspberrypi dbus-daemon[416]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=499 comm="/usr/lib/bluetooth/bluetoothd ")
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Bluetooth management interface 1.18 initialized
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Sap driver initialization failed.
Mar  8 20:43:43 raspberrypi bluetoothd[499]: sap-server: Operation not permitted (1)
Mar  8 20:43:43 raspberrypi systemd[1]: Starting Hostname Service...
Mar  8 20:43:43 raspberrypi dhcpcd[397]: wlan0: connected to Access Point `'
Mar  8 20:43:43 raspberrypi bluetoothd[499]: Failed to set privacy: Rejected (0x0b)
Mar  8 20:43:43 raspberrypi dhcpcd[397]: eth0: waiting for carrier
Mar  8 20:43:43 raspberrypi dhcpcd[397]: wlan0: waiting for carrier
Mar  8 20:43:43 raspberrypi kernel: [   10.977837] SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL)
Mar  8 20:43:43 raspberrypi kernel: [   10.978366] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Mar  8 20:43:43 raspberrypi kernel: [   10.996223] smsc95xx 1-1.1:1.0 eth0: Link is Down
Mar  8 20:43:43 raspberrypi dbus-daemon[416]: [system] Successfully activated service 'org.freedesktop.hostname1'
Mar  8 20:43:43 raspberrypi systemd[1]: Started Hostname Service.
Mar  8 20:43:43 raspberrypi raspi-config[387]:  No. Switching to ondemand scaling governor.
Mar  8 20:43:43 raspberrypi systemd[1]: Started LSB: Switch to ondemand cpu governor (unless shift key is pressed).
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: carrier acquired
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: connected to Access Point `Bbox-DEB7D788'
Mar  8 20:43:44 raspberrypi kernel: [   12.079383] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Mar  8 20:43:44 raspberrypi dhcpcd[397]: DUID 00:01:00:01:29:3a:ef:2b:b8:27:eb:ec:a2:b7
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: IAID eb:ec:a2:b7
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: adding address fe80::d72b:f5e5:e13a:76a9
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv6 with address fe80::d72b:f5e5:e13a:76a9.
Mar  8 20:43:44 raspberrypi dhcpcd[397]: wlan0: probing address 192.168.1.124/24
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: New relevant interface wlan0.IPv6 for mDNS.
Mar  8 20:43:44 raspberrypi avahi-daemon[404]: Registering new address record for fe80::d72b:f5e5:e13a:76a9 on wlan0.*.
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: soliciting an IPv6 router
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: Router Advertisement from fe80::6eff:ceff:fe2e:79fc
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding address 2001:861:4002:1090:e2c9:9eeb:7f33:b58d/64
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Leaving mDNS multicast group on interface wlan0.IPv6 with address fe80::d72b:f5e5:e13a:76a9.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv6 with address 2001:861:4002:1090:e2c9:9eeb:7f33:b58d.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Registering new address record for 2001:861:4002:1090:e2c9:9eeb:7f33:b58d on wlan0.*.
Mar  8 20:43:45 raspberrypi avahi-daemon[404]: Withdrawing address record for fe80::d72b:f5e5:e13a:76a9 on wlan0.
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding route to 2001:861:4002:1090::/64
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: requesting DHCPv6 information
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:45 raspberrypi kernel: [   13.306994] ICMPv6: process `dhcpcd' is using deprecated sysctl (syscall) net.ipv6.neigh.wlan0.retrans_time - use net.ipv6.neigh.wlan0.retrans_time_ms instead
Mar  8 20:43:45 raspberrypi dhcpcd[397]: wlan0: adding default route via fe80::6eff:ceff:fe2e:79fc
Mar  8 20:43:47 raspberrypi systemd[1]: systemd-rfkill.service: Succeeded.
Mar  8 20:43:47 raspberrypi bthelper[489]: Changing power off succeeded
Mar  8 20:43:48 raspberrypi bthelper[489]: Changing power on succeeded
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: using static address 192.168.1.124/24
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.124.
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: New relevant interface wlan0.IPv4 for mDNS.
Mar  8 20:43:49 raspberrypi avahi-daemon[404]: Registering new address record for 192.168.1.124 on wlan0.IPv4.
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: adding route to 192.168.1.0/24
Mar  8 20:43:49 raspberrypi dhcpcd[397]: wlan0: adding default route via 192.168.1.254
Mar  8 20:43:49 raspberrypi dhcpcd[397]: forked to background, child pid 570
Mar  8 20:43:49 raspberrypi systemd[1]: Started dhcpcd on all interfaces.
Mar  8 20:43:49 raspberrypi systemd[1]: Reached target Network.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Music Player Daemon...
Mar  8 20:43:49 raspberrypi systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Permit User Sessions...
Mar  8 20:43:49 raspberrypi systemd[1]: Starting OpenBSD Secure Shell server...
Mar  8 20:43:49 raspberrypi systemd[1]: Reached target Network is Online.
Mar  8 20:43:49 raspberrypi systemd[1]: Starting LSB: exim Mail Transport Agent...
Mar  8 20:43:49 raspberrypi systemd[1]: Starting Lighttpd Daemon...
Mar  8 20:43:49 raspberrypi systemd[1]: Started Phoniebox RFID-Reader Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Phoniebox GPIO Control Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Starting Samba NMB Daemon...
Mar  8 20:43:50 raspberrypi systemd[1]: Starting /etc/rc.local Compatibility...
Mar  8 20:43:50 raspberrypi systemd[1]: Started Phoniebox Idle Watchdog Service.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Permit User Sessions.
Mar  8 20:43:50 raspberrypi systemd[1]: Started /etc/rc.local Compatibility.
Mar  8 20:43:50 raspberrypi systemd[1]: Started Getty on tty1.
Mar  8 20:43:50 raspberrypi systemd[1]: Reached target Login Prompts.
Mar  8 20:43:50 raspberrypi systemd[1]: Started OpenBSD Secure Shell server.
Mar  8 20:43:51 raspberrypi lighttpd[575]: 2024-03-08 20:43:50: (server.c.1493) WARNING: unknown config-key: deflate.cache-dir (ignored)
Mar  8 20:43:51 raspberrypi systemd[1]: Started Lighttpd Daemon.
Mar  8 20:43:52 raspberrypi systemd[1]: Started Samba NMB Daemon.
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:GPIO Started
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:['VolumeControl', 'PrevNextControl', 'PlayPause', 'Shutdown', 'PauseShutdown', 'Volume0', 'VolumeUp', 'VolumeDown', 'NextSong', 'PrevSong', 'FastForward', 'Rewind', 'Halt', 'RFIDDevice']
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, VolumeControl
Mar  8 20:43:52 raspberrypi systemd[1]: Starting Samba SMB Daemon...
Mar  8 20:43:52 raspberrypi exim4[574]: Starting MTA: exim4.
Mar  8 20:43:52 raspberrypi systemd[1]: Started LSB: exim Mail Transport Agent.
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device PrevNextControl not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, PlayPause
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, Shutdown
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device PauseShutdown not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Volume0 not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device VolumeUp not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device VolumeDown not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, NextSong
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:adding GPIO-Device, PrevSong
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device FastForward not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Rewind not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device Halt not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Device RFIDDevice not enabled
Mar  8 20:43:52 raspberrypi PhonieboxGPIOControl[578]: INFO:__main__:Ready for taking actions
Mar  8 20:43:52 raspberrypi daemon_rfid_reader.py[576]: 2024-03-08 20:43:52,791 - root - INFO - Dir_PATH: /home/pi/RPi-Jukebox-RFID/scripts
Mar  8 20:43:53 raspberrypi systemd[1]: Started Samba SMB Daemon.
Mar  8 20:43:55 raspberrypi mpd: zeroconf: No global port, disabling zeroconf
Mar  8 20:43:55 raspberrypi systemd[1]: Started Music Player Daemon.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting Phoniebox Startup...
Mar  8 20:43:55 raspberrypi bash[913]: Phoniebox is starting...
Mar  8 20:43:55 raspberrypi bash[913]: 2.5.0
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOFOLDERSPATH="/home/pi/RPi-Jukebox-RFID/shared/audiofolders"
Mar  8 20:43:55 raspberrypi bash[913]: PLAYLISTSFOLDERPATH="/home/pi/RPi-Jukebox-RFID/playlists"
Mar  8 20:43:55 raspberrypi bash[913]: SWIPEORPLACE="SWIPENOTPLACE"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPE="NOAUDIOPLAY"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPEPAUSE="2"
Mar  8 20:43:55 raspberrypi bash[913]: SECONDSWIPEPAUSECONTROLS="ON"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOIFACENAME="Headphone"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOIFACEACTIVE="0"
Mar  8 20:43:55 raspberrypi bash[913]: VOLUMEMANAGER="mpd"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLCHANGESTEP="3"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLMAXLIMIT="60"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLMINLIMIT="1"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLSTARTUP="25"
Mar  8 20:43:55 raspberrypi bash[913]: AUDIOVOLBOOT="25"
Mar  8 20:43:55 raspberrypi bash[913]: VOLCHANGEIDLE="TRUE"
Mar  8 20:43:55 raspberrypi bash[913]: IDLETIMESHUTDOWN="0"
Mar  8 20:43:55 raspberrypi bash[913]: POWEROFFCMD="sudo poweroff"
Mar  8 20:43:55 raspberrypi bash[913]: SHOWCOVER="ON"
Mar  8 20:43:55 raspberrypi bash[913]: READWLANIPYN="OFF"
Mar  8 20:43:55 raspberrypi bash[913]: EDITION="classic"
Mar  8 20:43:55 raspberrypi bash[913]: LANG="fr-FR"
Mar  8 20:43:55 raspberrypi bash[913]: VERSION="2.5.0 - 4101984b - master"
Mar  8 20:43:55 raspberrypi bash[913]: CHAPTEREXTENSIONS="mp4,m4a,m4b,m4r"
Mar  8 20:43:55 raspberrypi bash[913]: CHAPTERMINDURATION="600"
Mar  8 20:43:55 raspberrypi bash[913]: CMDVOLUP=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDVOLDOWN=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDNEXT=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDPREV=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDREWIND=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDSEEKFORW=""
Mar  8 20:43:55 raspberrypi bash[913]: CMDSEEKBACK=""
Mar  8 20:43:55 raspberrypi bash[913]: 25 is the mpd startup volume
Mar  8 20:43:55 raspberrypi systemd[1]: Created slice User Slice of UID 1000.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting User Runtime Directory /run/user/1000...
Mar  8 20:43:55 raspberrypi systemd[1]: Started User Runtime Directory /run/user/1000.
Mar  8 20:43:55 raspberrypi systemd[1]: Starting User Manager for UID 1000...
Mar  8 20:43:55 raspberrypi bash[913]: OK MPD 0.21.4
Mar  8 20:43:55 raspberrypi bash[913]: OK
Mar  8 20:43:55 raspberrypi bash[913]: OK MPD 0.21.4
Mar  8 20:43:55 raspberrypi bash[913]: OK
Mar  8 20:43:55 raspberrypi bash[913]: 8192 is the mpg123 startup volume
Mar  8 20:43:56 raspberrypi bash[913]: High Performance MPEG 1.0/2.0/2.5 Audio Player for Layers 1, 2 and 3
Mar  8 20:43:56 raspberrypi bash[913]: #011version 1.25.10; written and copyright by Michael Hipp and others
Mar  8 20:43:56 raspberrypi bash[913]: #011free software (LGPL) without any warranty but with best wishes
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Timers.
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Paths.
Mar  8 20:43:56 raspberrypi systemd[954]: Listening on GnuPG network certificate management daemon.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Sockets.
Mar  8 20:43:56 raspberrypi systemd[954]: Reached target Basic System.
Mar  8 20:43:56 raspberrypi systemd[1]: Started User Manager for UID 1000.
Mar  8 20:43:56 raspberrypi systemd[954]: Starting Music Player Daemon...
Mar  8 20:43:56 raspberrypi systemd[1]: Started Session 1 of user pi.
Mar  8 20:43:56 raspberrypi bash[913]: Cannot connect to server socket err = No such file or directory
Mar  8 20:43:56 raspberrypi bash[913]: Cannot connect to server request channel
Mar  8 20:43:56 raspberrypi bash[913]: jack server is not running or cannot be started
Mar  8 20:43:56 raspberrypi bash[913]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Mar  8 20:43:56 raspberrypi bash[913]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Mar  8 20:43:56 raspberrypi bash[913]: /home/pi/RPi-Jukebox-RFID/scripts/startup-scripts.sh: line 53:  1032 Segmentation fault      /usr/bin/mpg123 -f -${mpgvolume} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Mar  8 20:43:56 raspberrypi bash[913]: Updating DB (#1) ...
Mar  8 20:43:56 raspberrypi bash[913]: volume: 25%   repeat: off   random: off   single: off   consume: off
Mar  8 20:43:56 raspberrypi mpd: update: updating Cuentos/Arreglar el mundo _ Cuento con valores (192kbit_AAC).m4a
Mar  8 20:43:56 raspberrypi systemd[1]: Started Phoniebox Startup.
Mar  8 20:43:56 raspberrypi dhcpcd[570]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:56 raspberrypi systemd[1]: Reached target Multi-User System.
Mar  8 20:43:56 raspberrypi dhcpcd[570]: wlan0: fe80::6eff:ceff:fe2e:79fc is reachable again
Mar  8 20:43:56 raspberrypi systemd[1]: Starting Update UTMP about System Runlevel Changes...
Mar  8 20:43:56 raspberrypi systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Mar  8 20:43:56 raspberrypi systemd[1]: Started Update UTMP about System Runlevel Changes.
Mar  8 20:43:56 raspberrypi systemd[1]: Startup finished in 3.985s (kernel) + 19.974s (userspace) = 23.959s.
Mar  8 20:43:56 raspberrypi systemd[1]: Started Session 3 of user pi.
Mar  8 20:43:56 raspberrypi mpd: update: updating Cuentos/Audiocuento El arbolito de navidad (192kbit_AAC).m4a
Mar  8 20:43:56 raspberrypi mpd: update: updating Cuentos/Audiocuento El flautista de Hamelin (192kbit_AAC).m4a
Mar  8 20:43:56 raspberrypi mpd: update: updating Cuentos/Audiocuento El gato con botas Cuento infantil (192kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating Cuentos/Audiocuento El tigre y el conejo Cuento infantil (192kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating Cuentos/¿Quién le pone el cascabel al gato_ _ Audiocuento con valores y sabiduría (192kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating Cuentos/Antiguo consejo chino _ Cuento para reflexionar (192kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/Little Bitty Pretty One-Thurston Harris-1957 (96kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/Jerry Lee Lewis - Great Balls of Fire (128kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/Rock Around the Clock-Bill Haley-original song-1955 (128kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/The Crystals - He's a Rebel (1962) (128kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/Little Darlin'-The Diamonds-1957 (96kbit_AAC).m4a
Mar  8 20:43:57 raspberrypi mpd: update: updating RocknRoll/The Best Rock and Roll Songs of the 50s - part two (128kbit_AAC).m4a
Mar  8 20:43:58 raspberrypi mpd: update: updating RocknRoll/The Best Rock and Roll Songs of the 50s - part one (128kbit_AAC).m4a
Mar  8 20:43:58 raspberrypi mpd: exception: Failed to bind to '[::1]:6600'
Mar  8 20:43:58 raspberrypi mpd: exception: nested: Failed to bind socket: Address already in use
Mar  8 20:43:58 raspberrypi systemd[954]: mpd.service: Main process exited, code=exited, status=1/FAILURE
Mar  8 20:43:58 raspberrypi systemd[954]: mpd.service: Failed with result 'exit-code'.
Mar  8 20:43:58 raspberrypi systemd[954]: Failed to start Music Player Daemon.
Mar  8 20:43:58 raspberrypi systemd[954]: Reached target Default.
Mar  8 20:43:58 raspberrypi systemd[954]: Startup finished in 2.686s.
Mar  8 20:43:58 raspberrypi mpd: update: updating RocknRoll/Will You Still Love Me Tomorrow - The Shirelles (152kbit_Opus).opus
Mar  8 20:43:58 raspberrypi mpd: update: updating RocknRoll/Jive Bunny - Rockabilly & 60's Oldies Monstermix (128kbit_AAC).m4a
Mar  8 20:43:59 raspberrypi mpd: update: updating RocknRoll/Sandra Dee & Bobby Darin-Dream Lover (128kbit_AAC).m4a
Mar  8 20:43:59 raspberrypi mpd: update: updating RocknRoll/Chuck Berry - Little Queenie (Official Video) (128kbit_AAC).m4a
Mar  8 20:43:59 raspberrypi mpd: update: updating RocknRoll/Little Richard - Bama Lama Bama Loo (128kbit_AAC).m4a
Mar  8 20:43:59 raspberrypi mpd: update: updating RocknRoll/Jive Bunny - Let's swing again.mp4 (128kbit_AAC).m4a
Mar  8 20:44:00 raspberrypi mpd: update: updating RocknRoll/Ben E. King - Stand by me (128kbit_Opus).opus
Mar  8 20:44:00 raspberrypi mpd: update: updating RocknRoll/Rockin' Robin-Bobby Day-original song-1957 (128kbit_Opus).opus
Mar  8 20:44:00 raspberrypi mpd: update: updating RocknRoll/Chuck Berry - Johnny B. Goode [HQ] (128kbit_AAC).m4a
Mar  8 20:44:00 raspberrypi mpd: update: updating RocknRoll/Chubby Checker - Let's Twist Again (lyrics) (128kbit_AAC).m4a
Mar  8 20:44:00 raspberrypi mpd: update: updating RocknRoll/Paul Anka Oh Carol (128kbit_AAC).m4a
Mar  8 20:44:00 raspberrypi mpd: update: updating Histoires/Henri Dès raconte - Le chat botté - histoire pour enfants (192kbit_AAC).m4a
Mar  8 20:44:00 raspberrypi mpd: update: updating Histoires/Le vilain petit Canard, conte d'Andersen (128kbit_AAC).m4a
Mar  8 20:44:01 raspberrypi mpd: update: updating Histoires/Henri Dès raconte - Les trois petits cochons - Histoire pour enfants (192kbit_AAC).m4a
Mar  8 20:44:01 raspberrypi mpd: update: updating Histoires/Histoire reposante et calmante pour enfants,  _Solange la souris_ livre audio _ audiobook (192kbit_AAC).m4a
Mar  8 20:44:01 raspberrypi mpd: update: updating Histoires/Aladin et la Lampe Magique _ Dessin animé complet en français _ Conte pour enfants (192kbit_AAC).m4a
Mar  8 20:44:02 raspberrypi mpd: update: updating Histoires/Le Petit Bonhomme en Pain d'épices _ 1 Conte + 4 comptines et chansons  - dessins animés en français (192kbit_AAC).m4a
Mar  8 20:44:02 raspberrypi mpd: update: updating Histoires/Livre Audio_ Le Diable Aux Trois Cheveux D'Or [Un Conte De Fées Des Frères Grimm] (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Lukas Graham - 7 Years [Official Music Video] (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Major Lazer - Cold Water (feat. Justin Bieber & MØ) (Official Lyric Video) (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Hit Sale (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Bebo Best & The Super Lounge Orchestra - Sing Sing Sing (Dance Video) _ Choreography _ MihranTV (192kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Unstoppable (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Angèle - Oui ou Non [CLIP OFFICIEL] (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Dernière danse (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Zara Larsson - Uncover (Official Music Video) (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/grease megamix (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Impossible (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Barbara Pravi - Kid (réécriture) (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Le Temps Qu'il Faut _ TAL _ Choreography by Ralph Beaubrun (192kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Shallow (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Sia - Cheap Thrills (Performance Edit) (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Yall feat. Gabriela Richardson - Hundred Miles (Official Video) (128kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Rockabye (feat. Sean Paul & Anne-Marie) (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Martin Garrix & Bebe Rexha - In The Name Of Love (Official Audio) (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Claudio Capéo - Un homme debout (clip officiel) (152kbit_Opus).opus
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/STAND _ Modern Jazz DANCE (192kbit_AAC).m4a
Mar  8 20:44:03 raspberrypi mpd: update: updating Modern_jazz/Taylor Swift - Shake It Off (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/All About That Bass - Postmodern Jukebox European Tour Version (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/Lean On (feat. MØ & DJ Snake) (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/Sing Sing Sing (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/Slimane - Paname (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/Jérémy Frerot - L'Homme Nouveau (clip officiel) (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Modern_jazz/The Mask Soundtrack - Royal Crown Revue - Hey! Pachuco! (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/Hercule - jamais je n'avouerai (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/Aladdin  - Prince Ali - en Français (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/5_Vaiana, la légende du bout du monde - Le Bleu Lumière I Disney (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/[HD] Le Roi Lion - L'amour brille sous les étoiles + Paroles (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/Marie Poppins - Supercalifragilisticexpilialidocious (128kbit_AAC).m4a
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/[HD] Dumbo - Mon tout petit (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/Aladdin _Ce rêve bleu_ (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/Alice au Pays des Merveilles - Extrait - Joyeux non anniversaire ! I Disney (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/04 Il en faut peu pour être heureux (152kbit_Opus).opus
Mar  8 20:44:04 raspberrypi mpd: update: updating Disney_French/la petite sirène sous l'océan (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Quand on prie la bonne étoile (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Raiponce _Où est la vrai vie_ HD (152kbit_Opus).opus
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Mulan - Reflection [French] (152kbit_Opus).opus
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Toy story 2 , Quand elle m'aimait. (152kbit_Opus).opus
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Le Roi Lion (The Lion King) Hakuna Matata (french) (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/01_La Reine des Neiges - Je voudrais un bonhomme de neige I Disney (152kbit_Opus).opus
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/La Belle et la Bête - Histoire éternelle _ Disney (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/4_D_Toy story 2 , Quand elle m'aimait. (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Pocahontas, une légende indienne - L'air du vent I Disney (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Tout le monde veut devenir un cat - Les Aristochats (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/Peter Pan - _Tu t'envoles_ - Disney (audio) (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/[HD] Le Roi Lion - Je voudrais déjà être Roi + Paroles (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/02_La Reine des Neiges - L'amour est un cadeau _ Disney (128kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/La Belle et le Clochard _Belle nuit.avi (192kbit_AAC).m4a
Mar  8 20:44:05 raspberrypi mpd: update: updating Disney_French/3_Charlélie Couture - Etrange Bazar (BOF Toy Story-1_Exclu from MCM) (128kbit_AAC).m4a
Mar  8 20:44:06 raspberrypi mpd: update: updating Disney_French/2_Toy story 1 , Je suis ton ami. (152kbit_Opus).opus
Mar  8 20:44:06 raspberrypi mpd: update: updating Disney_French/4_Charlélie Couture - Jamais Plus Je Ne Volerai (Toy Story OST) (152kbit_Opus).opus
Mar  8 20:44:06 raspberrypi mpd: update: updating Disney_French/00_La Reine des Neiges - Libérée, délivrée I Disney (152kbit_Opus).opus
Mar  8 20:44:06 raspberrypi mpd: update: updating Disney_French/Le Roi Lion _L'histoire de la vie_ HD (128kbit_AAC).m4a
Mar  8 20:44:06 raspberrypi mpd: update: updating Disney_French/Cendrillon - Bibbidi-Bobbidi-Boo HD (128kbit_AAC).m4a
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 10 - Una Hormiguita.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 03 - Con Mi Dedito.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 16 - El Camión Azul.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 11 - Arramsamsam.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 14 - La Cancion Del Click.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 03 - Para Dormir A Un Elefante.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 02 - A Mi Burro.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 13 - Si Tú Tienes Muchas Ganas.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 12 - A Mi Mono Le Gusta La Lechuga.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 04 - Ronda De Los Conejos.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 10 - La Brujita Tapita.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 11 - Hocky Pocky.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 01 - Popurri Del Tallarin.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 14 - Cinco Ratoncitos.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 06 - Tachín.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 15 - El Cuento Del Zapatero.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 18 - Chuchuá.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 15 - Estaba El Señor Don Gato.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 13 - Este Puente Va A Caer.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 01 - El Patio De Mi Casa.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 19 - El Zapatero, Tortuguina Y Rapidín (Cuento).mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 07 - Ladrillo A Ladrillo.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 04 - El Pollito Lito.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 06 - El Burrito Pepe.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 02 - Popurri De Las Manos.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 05 - En Un Vagón.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 07 - El Zapatero.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 09 - El Zapatero, Tortuguina Y Rapidín.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 08 - Salta La Perdiz.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 09 - La Forma De Caminar.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 17 - Tengo Una Hormiguita En La Patita.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 08 - Chocolate.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 12 - El Ratón Pérez.mp3
Mar  8 20:44:06 raspberrypi mpd: update: updating Cantajuegos/Cantajuego - 05 - El Arca De Noe.mp3
Mar  8 20:44:10 raspberrypi systemd[1]: systemd-fsckd.service: Succeeded.
Mar  8 20:44:38 raspberrypi systemd-timesyncd[345]: Synchronized to time server for the first time [2001:418:3ff::53]:123 (2.debian.pool.ntp.org).
Mar  8 20:44:39 raspberrypi systemd[1]: systemd-hostnamed.service: Succeeded.
Mar  8 20:45:16 raspberrypi systemd[1]: phoniebox-idle-watchdog.service: Succeeded.
Mar  8 20:50:40 raspberrypi systemd[1]: Started Session 4 of user pi.