s-martin
commented
3 years ago Please provide the output of tail -n 500 /var/log/syslog.
What's the output of cat ~/RPi-Jukebox-RFID/settings/gpio_settings.ini?
Closed Kopernikus82 closed 2 years ago
s-martin
commented
3 years ago Please provide the output of tail -n 500 /var/log/syslog.
What's the output of cat ~/RPi-Jukebox-RFID/settings/gpio_settings.ini?
Kopernikus82
commented
3 years ago Vielleicht auch noch mal als Hinweis, die LED sind mit Pullup Wiederständen am Pi angeschlossen. Vor dem Update lief auch alles anstandslos....
tail -n 500 /var/log/syslog.
Dec 15 11:52:24 phoniebox kernel: [ 0.100884] audit: type=2000 audit(0.090:1) : state=initialized audit_enabled=0 res=1
Dec 15 11:52:24 phoniebox kernel: [ 0.120832] raspberrypi-firmware soc:firmwa re: Attached to firmware from 2020-11-30 22:13, variant start
Dec 15 11:52:24 phoniebox kernel: [ 0.130857] raspberrypi-firmware soc:firmwa re: Firmware hash is ab1181cc0cb6df52bfae3b1d3fef0ce7c325166c
Dec 15 11:52:24 phoniebox kernel: [ 0.195741] bcm2835-dma 20007000.dma: DMA l egacy API manager, dmachans=0x1
Dec 15 11:52:24 phoniebox kernel: [ 0.199020] SCSI subsystem initialized
Dec 15 11:52:24 phoniebox kernel: [ 0.199350] usbcore: registered new interfa ce driver usbfs
Dec 15 11:52:24 phoniebox kernel: [ 0.199508] usbcore: registered new interfa ce driver hub
Dec 15 11:52:24 phoniebox kernel: [ 0.199815] usbcore: registered new device driver usb
Dec 15 11:52:24 phoniebox kernel: [ 0.202741] clocksource: Switched to clocks ource timer
Dec 15 11:52:24 phoniebox kernel: [ 1.682326] VFS: Disk quotas dquot_6.6.0
Dec 15 11:52:24 phoniebox kernel: [ 1.682554] VFS: Dquot-cache hash table ent ries: 1024 (order 0, 4096 bytes)
Dec 15 11:52:24 phoniebox kernel: [ 1.683088] FS-Cache: Loaded
Dec 15 11:52:24 phoniebox kernel: [ 1.683517] CacheFiles: Loaded
Dec 15 11:52:24 phoniebox kernel: [ 1.704918] thermal_sys: Registered thermal governor 'step_wise'
Dec 15 11:52:24 phoniebox kernel: [ 1.705646] NET: Registered protocol family 2
Dec 15 11:52:24 phoniebox kernel: [ 1.707151] tcp_listen_portaddr_hash hash t able entries: 512 (order: 0, 4096 bytes, linear)
Dec 15 11:52:24 phoniebox kernel: [ 1.707282] TCP established hash table entr ies: 4096 (order: 2, 16384 bytes, linear)
Dec 15 11:52:24 phoniebox kernel: [ 1.707403] TCP bind hash table entries: 40 96 (order: 2, 16384 bytes, linear)
Dec 15 11:52:24 phoniebox kernel: [ 1.707530] TCP: Hash tables configured (es tablished 4096 bind 4096)
Dec 15 11:52:24 phoniebox kernel: [ 1.707757] UDP hash table entries: 256 (or der: 0, 4096 bytes, linear)
Dec 15 11:52:24 phoniebox kernel: [ 1.707837] UDP-Lite hash table entries: 25 6 (order: 0, 4096 bytes, linear)
Dec 15 11:52:24 phoniebox kernel: [ 1.708394] NET: Registered protocol family 1
Dec 15 11:52:24 phoniebox kernel: [ 1.709807] RPC: Registered named UNIX sock et transport module.
Dec 15 11:52:24 phoniebox kernel: [ 1.709876] RPC: Registered udp transport m odule.
Dec 15 11:52:24 phoniebox kernel: [ 1.709913] RPC: Registered tcp transport m odule.
Dec 15 11:52:24 phoniebox kernel: [ 1.709947] RPC: Registered tcp NFSv4.1 bac kchannel transport module.
Dec 15 11:52:24 phoniebox kernel: [ 1.711612] hw perfevents: no irqs for PMU, sampling events not supported
Dec 15 11:52:24 phoniebox kernel: [ 1.711742] hw perfevents: enabled with arm v6_1176 PMU driver, 3 counters available
Dec 15 11:52:24 phoniebox kernel: [ 1.716843] Initialise system trusted keyri ngs
Dec 15 11:52:24 phoniebox kernel: [ 1.717396] workingset: timestamp_bits=14 m ax_order=17 bucket_order=3
Dec 15 11:52:24 phoniebox kernel: [ 1.735738] FS-Cache: Netfs 'nfs' registere d for caching
Dec 15 11:52:24 phoniebox kernel: [ 1.737521] NFS: Registering the id_resolve r key type
Dec 15 11:52:24 phoniebox kernel: [ 1.737675] Key type id_resolver registered
Dec 15 11:52:24 phoniebox kernel: [ 1.737716] Key type id_legacy registered
Dec 15 11:52:24 phoniebox kernel: [ 1.737775] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Dec 15 11:52:24 phoniebox kernel: [ 1.740226] Key type asymmetric registered
Dec 15 11:52:24 phoniebox kernel: [ 1.740301] Asymmetric key parser 'x509' re gistered
Dec 15 11:52:24 phoniebox kernel: [ 1.740452] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Dec 15 11:52:24 phoniebox kernel: [ 1.740508] io scheduler mq-deadline regist ered
Dec 15 11:52:24 phoniebox kernel: [ 1.740546] io scheduler kyber registered
Dec 15 11:52:24 phoniebox kernel: [ 1.744296] bcm2708_fb soc:fb: FB found 1 d isplay(s)
Dec 15 11:52:24 phoniebox kernel: [ 1.757027] Console: switching to colour fr ame buffer device 82x26
Dec 15 11:52:24 phoniebox kernel: [ 1.764799] bcm2708_fb soc:fb: Registered f ramebuffer for display 0, size 656x416
Dec 15 11:52:24 phoniebox kernel: [ 1.778818] bcm2835-rng 20104000.rng: hwrng registered
Dec 15 11:52:24 phoniebox kernel: [ 1.782505] vc-mem: phys_addr:0x00000000 me m_base=0x1ec00000 mem_size:0x20000000(512 MiB)
Dec 15 11:52:24 phoniebox kernel: [ 1.789741] vc-sm: Videocore shared memory driver
Dec 15 11:52:24 phoniebox kernel: [ 1.793816] gpiomem-bcm2835 20200000.gpiome m: Initialised: Registers at 0x20200000
Dec 15 11:52:24 phoniebox kernel: [ 1.823127] brd: module loaded
Dec 15 11:52:24 phoniebox kernel: [ 1.842164] loop: module loaded
Dec 15 11:52:24 phoniebox kernel: [ 1.847552] Loading iSCSI transport class v 2.0-870.
Dec 15 11:52:24 phoniebox kernel: [ 1.851775] usbcore: registered new interfa ce driver smsc95xx
Dec 15 11:52:24 phoniebox kernel: [ 1.854949] dwc_otg: version 3.00a 10-AUG-2 012 (platform bus)
Dec 15 11:52:24 phoniebox kernel: [ 2.502747] random: fast init done
Dec 15 11:52:24 phoniebox kernel: [ 2.589771] Core Release: 2.80a
Dec 15 11:52:24 phoniebox kernel: [ 2.592685] Setting default values for core params
Dec 15 11:52:24 phoniebox kernel: [ 2.595592] Finished setting default values for core params
Dec 15 11:52:24 phoniebox kernel: [ 2.799061] Using Buffer DMA mode
Dec 15 11:52:24 phoniebox kernel: [ 2.801935] Periodic Transfer Interrupt Enh ancement - disabled
Dec 15 11:52:24 phoniebox kernel: [ 2.804880] Multiprocessor Interrupt Enhanc ement - disabled
Dec 15 11:52:24 phoniebox kernel: [ 2.807795] OTG VER PARAM: 0, OTG VER FLAG: 0
Dec 15 11:52:24 phoniebox kernel: [ 2.810696] Dedicated Tx FIFOs mode
Dec 15 11:52:24 phoniebox kernel: [ 2.814455] WARN::dwc_otg_hcd_init:1074: FI Q DMA bounce buffers: virt = d7d14000 dma = 0x97d14000 len=9024
Dec 15 11:52:24 phoniebox kernel: [ 2.820053] FIQ FSM acceleration enabled fo r :
Dec 15 11:52:24 phoniebox kernel: [ 2.820053] Non-periodic Split Transactions
Dec 15 11:52:24 phoniebox kernel: [ 2.820053] Periodic Split Transactions
Dec 15 11:52:24 phoniebox kernel: [ 2.820053] High-Speed Isochronous Endpoint s
Dec 15 11:52:24 phoniebox kernel: [ 2.820053] Interrupt/Control Split Transac tion hack enabled
Dec 15 11:52:24 phoniebox kernel: [ 2.832925] dwc_otg: Microframe scheduler e nabled
Dec 15 11:52:24 phoniebox kernel: [ 2.833157] WARN::hcd_init_fiq:457: FIQ on core 0
Dec 15 11:52:24 phoniebox kernel: [ 2.835770] WARN::hcd_init_fiq:458: FIQ ASM at c059fe8c length 36
Dec 15 11:52:24 phoniebox kernel: [ 2.838361] WARN::hcd_init_fiq:497: MPHI re gs_base at dc810000
Dec 15 11:52:24 phoniebox kernel: [ 2.840952] dwc_otg 20980000.usb: DWC OTG C ontroller
Dec 15 11:52:24 phoniebox kernel: [ 2.843659] dwc_otg 20980000.usb: new USB b us registered, assigned bus number 1
Dec 15 11:52:24 phoniebox kernel: [ 2.846378] dwc_otg 20980000.usb: irq 56, i o mem 0x00000000
Dec 15 11:52:24 phoniebox kernel: [ 2.849044] Init: Port Power? op_state=1
Dec 15 11:52:24 phoniebox kernel: [ 2.851592] Init: Power Port (0)
Dec 15 11:52:24 phoniebox kernel: [ 2.854662] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04
Dec 15 11:52:24 phoniebox kernel: [ 2.859837] usb usb1: New USB device string s: Mfr=3, Product=2, SerialNumber=1
Dec 15 11:52:24 phoniebox kernel: [ 2.862587] usb usb1: Product: DWC OTG Cont roller
Dec 15 11:52:24 phoniebox kernel: [ 2.865355] usb usb1: Manufacturer: Linux 5 .4.79+ dwc_otg_hcd
Dec 15 11:52:24 phoniebox kernel: [ 2.868097] usb usb1: SerialNumber: 2098000 0.usb
Dec 15 11:52:24 phoniebox kernel: [ 2.872031] hub 1-0:1.0: USB hub found
Dec 15 11:52:24 phoniebox kernel: [ 2.874881] hub 1-0:1.0: 1 port detected
Dec 15 11:52:24 phoniebox kernel: [ 2.878796] dwc_otg: FIQ enabled
Dec 15 11:52:24 phoniebox kernel: [ 2.878813] dwc_otg: NAK holdoff enabled
Dec 15 11:52:24 phoniebox kernel: [ 2.878824] dwc_otg: FIQ split-transaction FSM enabled
Dec 15 11:52:24 phoniebox kernel: [ 2.878848] Module dwc_common_port init
Dec 15 11:52:24 phoniebox kernel: [ 2.879472] usbcore: registered new interfa ce driver usb-storage
Dec 15 11:52:24 phoniebox kernel: [ 2.882851] mousedev: PS/2 mouse device com mon for all mice
Dec 15 11:52:24 phoniebox kernel: [ 2.887339] bcm2835-wdt bcm2835-wdt: Broadc om BCM2835 watchdog timer
Dec 15 11:52:24 phoniebox kernel: [ 2.893871] sdhci: Secure Digital Host Cont roller Interface driver
Dec 15 11:52:24 phoniebox kernel: [ 2.896726] sdhci: Copyright(c) Pierre Ossm an
Dec 15 11:52:24 phoniebox kernel: [ 2.900288] mmc-bcm2835 20300000.mmcnr: cou ld not get clk, deferring probe
Dec 15 11:52:24 phoniebox kernel: [ 2.904466] sdhost-bcm2835 20202000.mmc: co uld not get clk, deferring probe
Dec 15 11:52:24 phoniebox kernel: [ 2.907830] sdhci-pltfm: SDHCI platform and OF driver helper
Dec 15 11:52:24 phoniebox kernel: [ 2.911667] ledtrig-cpu: registered to indi cate activity on CPUs
Dec 15 11:52:24 phoniebox kernel: [ 2.915323] hidraw: raw HID events driver ( C) Jiri Kosina
Dec 15 11:52:24 phoniebox kernel: [ 2.918556] usbcore: registered new interfa ce driver usbhid
Dec 15 11:52:24 phoniebox kernel: [ 2.921457] usbhid: USB HID core driver
Dec 15 11:52:24 phoniebox kernel: [ 2.925895] vchiq: vchiq_init_state: slot_z ero = (ptrval)
Dec 15 11:52:24 phoniebox kernel: [ 2.931156] [vc_sm_connected_init]: start
Dec 15 11:52:24 phoniebox kernel: [ 2.941633] [vc_sm_connected_init]: end - r eturning 0
Dec 15 11:52:24 phoniebox kernel: [ 2.947288] Initializing XFRM netlink socke t
Dec 15 11:52:24 phoniebox kernel: [ 2.950100] NET: Registered protocol family 17
Dec 15 11:52:24 phoniebox kernel: [ 2.953035] Key type dns_resolver registere d
Dec 15 11:52:24 phoniebox kernel: [ 2.957041] registered taskstats version 1
Dec 15 11:52:24 phoniebox kernel: [ 2.959769] Loading compiled-in X.509 certi ficates
Dec 15 11:52:24 phoniebox kernel: [ 2.978209] uart-pl011 20201000.serial: cts _event_workaround enabled
Dec 15 11:52:24 phoniebox kernel: [ 2.981232] 20201000.serial: ttyAMA0 at MMI O 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
Dec 15 11:52:24 phoniebox kernel: [ 2.989740] bcm2835-power bcm2835-power: Br oadcom BCM2835 power domains driver
Dec 15 11:52:24 phoniebox kernel: [ 2.995191] mmc-bcm2835 20300000.mmcnr: mmc _debug:0 mmc_debug2:0
Dec 15 11:52:24 phoniebox kernel: [ 2.998206] mmc-bcm2835 20300000.mmcnr: DMA channel allocated
Dec 15 11:52:24 phoniebox kernel: [ 3.028809] sdhost: log_buf @ (ptrval) (97d 13000)
Dec 15 11:52:24 phoniebox kernel: [ 3.069516] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Dec 15 11:52:24 phoniebox kernel: [ 3.074208] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec 15 11:52:24 phoniebox kernel: [ 3.078694] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec 15 11:52:24 phoniebox kernel: [ 3.081496] mmc0: sdhost-bcm2835 loaded - D MA enabled (>1)
Dec 15 11:52:24 phoniebox kernel: [ 3.087490] of_cfs_init
Dec 15 11:52:24 phoniebox kernel: [ 3.090266] of_cfs_init: OK
Dec 15 11:52:24 phoniebox kernel: [ 3.113862] Indeed it is in host mode hprt0 = 00041901
Dec 15 11:52:24 phoniebox kernel: [ 3.178330] Waiting for root device PARTUUI D=6c586e13-02...
Dec 15 11:52:24 phoniebox kernel: [ 3.183169] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Dec 15 11:52:24 phoniebox kernel: [ 3.249775] mmc0: host does not support rea ding read-only switch, assuming write-enable
Dec 15 11:52:24 phoniebox kernel: [ 3.259307] mmc0: new high speed SDXC card at address aaaa
Dec 15 11:52:24 phoniebox kernel: [ 3.264656] mmcblk0: mmc0:aaaa SC64G 59.5 G iB
Dec 15 11:52:24 phoniebox kernel: [ 3.274868] mmcblk0: p1 p2
Dec 15 11:52:24 phoniebox kernel: [ 3.315702] EXT4-fs (mmcblk0p2): mounted fi lesystem with ordered data mode. Opts: (null)
Dec 15 11:52:24 phoniebox kernel: [ 3.321165] VFS: Mounted root (ext4 filesys tem) readonly on device 179:2.
Dec 15 11:52:24 phoniebox kernel: [ 3.324205] usb 1-1: new low-speed USB devi ce number 2 using dwc_otg
Dec 15 11:52:24 phoniebox kernel: [ 3.329828] Indeed it is in host mode hprt0 = 00041901
Dec 15 11:52:24 phoniebox kernel: [ 3.397879] mmc1: new high speed SDIO card at address 0001
Dec 15 11:52:24 phoniebox kernel: [ 3.404985] devtmpfs: mounted
Dec 15 11:52:24 phoniebox kernel: [ 3.413840] Freeing unused kernel memory: 5 12K
Dec 15 11:52:24 phoniebox kernel: [ 3.416736] This architecture does not have kernel memory protection.
Dec 15 11:52:24 phoniebox kernel: [ 3.419676] Run /sbin/init as init process
Dec 15 11:52:24 phoniebox kernel: [ 3.589267] usb 1-1: New USB device found, idVendor=16c0, idProduct=27db, bcdDevice= 0.01
Dec 15 11:52:24 phoniebox kernel: [ 3.595660] usb 1-1: New USB device strings : Mfr=1, Product=0, SerialNumber=0
Dec 15 11:52:24 phoniebox kernel: [ 3.599014] usb 1-1: Manufacturer: HXGCoLtd
Dec 15 11:52:24 phoniebox kernel: [ 3.624918] input: HXGCoLtd Keyboard as /de vices/platform/soc/20980000.usb/usb1/1-1/1-1:1.0/0003:16C0:27DB.0001/input/input 0
Dec 15 11:52:24 phoniebox kernel: [ 3.704583] hid-generic 0003:16C0:27DB.0001 : input,hiddev96,hidraw0: USB HID v1.10 Keyboard [HXGCoLtd] on usb-20980000.usb- 1/input0
Dec 15 11:52:24 phoniebox kernel: [ 4.444493] NET: Registered protocol family 10
Dec 15 11:52:24 phoniebox kernel: [ 4.450578] Segment Routing with IPv6
Dec 15 11:52:24 phoniebox kernel: [ 7.761609] random: systemd: uninitialized urandom read (16 bytes read)
Dec 15 11:52:24 phoniebox kernel: [ 7.809167] random: systemd: uninitialized urandom read (16 bytes read)
Dec 15 11:52:24 phoniebox kernel: [ 7.827704] random: systemd: uninitialized urandom read (16 bytes read)
Dec 15 11:52:24 phoniebox kernel: [ 12.845154] EXT4-fs (mmcblk0p2): re-mounted . Opts: (null)
Dec 15 11:52:24 phoniebox kernel: [ 15.817142] vc_sm_cma: module is from the s taging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 15.820144] bcm2835_vc_sm_cma_probe: Videoc ore shared memory driver
Dec 15 11:52:24 phoniebox kernel: [ 15.820175] [vc_sm_connected_init]: start
Dec 15 11:52:24 phoniebox kernel: [ 15.847045] mc: Linux media interface: v0.1 0
Dec 15 11:52:24 phoniebox kernel: [ 15.863183] [vc_sm_connected_init]: install ed successfully
Dec 15 11:52:24 phoniebox kernel: [ 16.133616] videodev: Linux video capture i nterface: v2.00
Dec 15 11:52:24 phoniebox kernel: [ 16.212114] bcm2835_mmal_vchiq: module is f rom the staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.214313] bcm2835_mmal_vchiq: module is f rom the staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.225673] bcm2835_mmal_vchiq: module is f rom the staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.259047] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.316085] bcm2835_v4l2: module is from th e staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.345982] bcm2835_codec: module is from t he staging directory, the quality is unknown, you have been warned.
Dec 15 11:52:24 phoniebox kernel: [ 16.352136] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Dec 15 11:52:24 phoniebox kernel: [ 16.375724] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Dec 15 11:52:24 phoniebox kernel: [ 16.376793] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Dec 15 11:52:24 phoniebox kernel: [ 16.404278] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Dec 15 11:52:24 phoniebox kernel: [ 16.404337] bcm2835-isp bcm2835-isp: Regist er output node 0 with media controller
Dec 15 11:52:24 phoniebox kernel: [ 16.404374] bcm2835-isp bcm2835-isp: Regist er capture node 1 with media controller
Dec 15 11:52:24 phoniebox kernel: [ 16.404402] bcm2835-isp bcm2835-isp: Regist er capture node 2 with media controller
Dec 15 11:52:24 phoniebox kernel: [ 16.404430] bcm2835-isp bcm2835-isp: Regist er capture node 3 with media controller
Dec 15 11:52:24 phoniebox kernel: [ 16.406877] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Dec 15 11:52:24 phoniebox kernel: [ 16.478198] bcm2835-codec bcm2835-codec: De vice registered as /dev/video10
Dec 15 11:52:24 phoniebox kernel: [ 16.478280] bcm2835-codec bcm2835-codec: Lo aded V4L2 decode
Dec 15 11:52:24 phoniebox kernel: [ 16.574936] bcm2835-codec bcm2835-codec: De vice registered as /dev/video11
Dec 15 11:52:24 phoniebox kernel: [ 16.575019] bcm2835-codec bcm2835-codec: Lo aded V4L2 encode
Dec 15 11:52:24 phoniebox kernel: [ 16.589823] bcm2835-codec bcm2835-codec: De vice registered as /dev/video12
Dec 15 11:52:24 phoniebox kernel: [ 16.589932] bcm2835-codec bcm2835-codec: Lo aded V4L2 isp
Dec 15 11:52:24 phoniebox kernel: [ 16.900348] snd-rpi-simple soc:sound: pcm51 02a-hifi <-> 20203000.i2s mapping ok
Dec 15 11:52:24 phoniebox kernel: [ 18.278697] cfg80211: Loading compiled-in X .509 certificates for regulatory database
Dec 15 11:52:24 phoniebox kernel: [ 18.707070] cfg80211: Loaded X.509 cert 'sf orshee: 00b28ddf47aef9cea7'
Dec 15 11:52:24 phoniebox kernel: [ 19.240806] brcmfmac: F1 signature read @0x 18000000=0x1541a9a6
Dec 15 11:52:24 phoniebox kernel: [ 19.263035] brcmfmac: brcmf_fw_alloc_reques t: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Dec 15 11:52:24 phoniebox kernel: [ 19.263522] usbcore: registered new interfa ce driver brcmfmac
Dec 15 11:52:24 phoniebox kernel: [ 19.289141] brcmfmac mmc1:0001:1: Direct fi rmware load for brcm/brcmfmac43430-sdio.raspberrypi,model-zero-w.txt failed with error -2
Dec 15 11:52:24 phoniebox kernel: [ 19.566695] brcmfmac: brcmf_fw_alloc_reques t: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Dec 15 11:52:24 phoniebox kernel: [ 19.566930] brcmfmac: brcmf_c_process_clm_b lob: no clm_blob available (err=-2), device may have limited channels available
Dec 15 11:52:24 phoniebox kernel: [ 19.568173] brcmfmac: brcmf_c_preinit_dcmds : Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd
Dec 15 11:52:24 phoniebox kernel: [ 21.452153] random: crng init done
Dec 15 11:52:24 phoniebox kernel: [ 21.452178] random: 7 urandom warning(s) mi ssed due to ratelimiting
Dec 15 11:52:24 phoniebox systemd[1]: Listening on mpd.socket.
Dec 15 11:52:24 phoniebox systemd[1]: Reached target Sockets.
Dec 15 11:52:24 phoniebox systemd[1]: Reached target Basic System.
Dec 15 11:52:24 phoniebox systemd[1]: Starting System Logging Service...
Dec 15 11:52:24 phoniebox systemd[1]: Starting Login Service...
Dec 15 11:52:24 phoniebox systemd[1]: Condition check resulted in getty on tty2- tty6 if dbus and logind are not available being skipped.
Dec 15 11:52:24 phoniebox systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Dec 15 11:52:24 phoniebox systemd[1]: Started D-Bus System Message Bus.
Dec 15 11:52:24 phoniebox rsyslogd: imuxsock: Acquired UNIX socket '/run/systemd /journal/syslog' (fd 3) from systemd. [v8.1901.0]
Dec 15 11:52:24 phoniebox rsyslogd: [origin software="rsyslogd" swVersion="8.19 01.0" x-pid="266" x-info="https://www.rsyslog.com"] start
Dec 15 11:52:24 phoniebox avahi-daemon[268]: Found user 'avahi' (UID 108) and gr oup 'avahi' (GID 113).
Dec 15 11:52:24 phoniebox avahi-daemon[268]: Successfully dropped root privilege s.
Dec 15 11:52:24 phoniebox avahi-daemon[268]: avahi-daemon 0.7 starting up.
Dec 15 11:52:24 phoniebox systemd[1]: Starting LSB: Switch to ondemand cpu gover nor (unless shift key is pressed)...
Dec 15 11:52:25 phoniebox systemd[1]: Starting LSB: Monitoring GPIO shutdown tri gger...
Dec 15 11:52:25 phoniebox systemd[1]: Started Regular background program process ing daemon.
Dec 15 11:52:25 phoniebox cron[277]: (CRON) INFO (pidfile fd = 3)
Dec 15 11:52:25 phoniebox systemd[1]: Starting triggerhappy global hotkey daemon ...
Dec 15 11:52:25 phoniebox systemd[1]: Condition check resulted in Copy user wpa_ supplicant.conf being skipped.
Dec 15 11:52:25 phoniebox avahi-daemon[268]: Successfully called chroot().
Dec 15 11:52:25 phoniebox avahi-daemon[268]: Successfully dropped remaining capa bilities.
Dec 15 11:52:25 phoniebox avahi-daemon[268]: No service file found in /etc/avahi /services.
Dec 15 11:52:25 phoniebox avahi-daemon[268]: Network interface enumeration compl eted.
Dec 15 11:52:25 phoniebox avahi-daemon[268]: Server startup complete. Host name is phoniebox.local. Local service cookie is 4245393370.
Dec 15 11:52:25 phoniebox cron[277]: (CRON) INFO (Running @reboot jobs)
Dec 15 11:52:25 phoniebox systemd[1]: Starting Deferred execution scheduler...
Dec 15 11:52:25 phoniebox thd[281]: Found socket passed from systemd
Dec 15 11:52:25 phoniebox systemd[1]: Condition check resulted in Turn on SSH if /boot/ssh is present being skipped.
Dec 15 11:52:25 phoniebox systemd[1]: Started Manage Sound Card State (restore a nd store).
Dec 15 11:52:25 phoniebox systemd[1]: Started Phoniebox LED-Buttons-Control Serv ice.
Dec 15 11:52:25 phoniebox alsactl[284]: alsactl 1.1.8 daemon started
Dec 15 11:52:25 phoniebox systemd[1]: Starting dhcpcd on all interfaces...
Dec 15 11:52:25 phoniebox systemd[1]: Starting WPA supplicant...
Dec 15 11:52:25 phoniebox systemd[1]: Starting Save/Restore Sound Card State...
Dec 15 11:52:26 phoniebox systemd[1]: Starting Check for Raspberry Pi EEPROM upd ates...
Dec 15 11:52:26 phoniebox dhcpcd[286]: dev: loaded udev
Dec 15 11:52:26 phoniebox systemd[1]: Starting dphys-swapfile - set up, mount/un mount, and delete a swap file...
Dec 15 11:52:26 phoniebox systemd[1]: Starting rng-tools.service...
Dec 15 11:52:26 phoniebox kernel: [ 29.137267] 8021q: 802.1Q VLAN Support v1.8
Dec 15 11:52:26 phoniebox systemd[1]: Starting Configure Bluetooth Modems connec ted by UART...
Dec 15 11:52:26 phoniebox wpa_supplicant[288]: Successfully initialized wpa_supp licant
Dec 15 11:52:26 phoniebox rng-tools[295]: Starting Hardware RNG entropy gatherer daemon: rngd.
Dec 15 11:52:26 phoniebox rngd[307]: rngd 2-unofficial-mt.14 starting up...
Dec 15 11:52:26 phoniebox systemd[1]: Started System Logging Service.
Dec 15 11:52:26 phoniebox systemd[1]: Started triggerhappy global hotkey daemon.
Dec 15 11:52:26 phoniebox rngd[307]: entropy feed to the kernel ready
Dec 15 11:52:27 phoniebox systemd[1]: Started Deferred execution scheduler.
Dec 15 11:52:27 phoniebox systemd[1]: Started Save/Restore Sound Card State.
Dec 15 11:52:27 phoniebox systemd[1]: Started Check for Raspberry Pi EEPROM upda tes.
Dec 15 11:52:27 phoniebox dhcpcd[286]: wlan0: starting wpa_supplicant
Dec 15 11:52:27 phoniebox systemd[1]: Started rng-tools.service.
Dec 15 11:52:27 phoniebox dhcpcd-run-hooks[334]: wlan0: starting wpa_supplicant
Dec 15 11:52:27 phoniebox dbus-daemon[269]: [system] Successfully activated serv ice 'org.freedesktop.systemd1'
Dec 15 11:52:27 phoniebox kernel: [ 30.471715] uart-pl011 20201000.serial: no DMA platform data
Dec 15 11:52:27 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Dec 15 11:52:28 phoniebox dphys-swapfile[292]: want /var/swap=100MByte, checking existing: keeping it
Dec 15 11:52:28 phoniebox cleanshutd[275]: Starting...
Dec 15 11:52:28 phoniebox systemd[1]: Started LSB: Monitoring GPIO shutdown trig ger.
Dec 15 11:52:28 phoniebox kernel: [ 31.281430] brcmfmac: brcmf_cfg80211_set_po wer_mgmt: power save enabled
Dec 15 11:52:28 phoniebox systemd[1]: Reached target Sound Card.
Dec 15 11:52:28 phoniebox systemd[1]: Started Login Service.
Dec 15 11:52:28 phoniebox kernel: [ 31.505622] Adding 102396k swap on /var/swa p. Priority:-2 extents:1 across:102396k SSFS
Dec 15 11:52:28 phoniebox systemd[1]: Started WPA supplicant.
Dec 15 11:52:28 phoniebox systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Dec 15 11:52:29 phoniebox systemd[1]: Started dphys-swapfile - set up, mount/unm ount, and delete a swap file.
Dec 15 11:52:29 phoniebox raspi-config[274]: Checking if shift key is held down: No. Switching to ondemand scaling governor.
Dec 15 11:52:29 phoniebox systemd[1]: Started LSB: Switch to ondemand cpu govern or (unless shift key is pressed).
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: connected to Access Point `'
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: waiting for carrier
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: carrier acquired
Dec 15 11:52:29 phoniebox dhcpcd[286]: DUID 00:01:00:01:25:1e:c1:d0:b8:27:eb:2c: 8c:2d
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: IAID eb:2c:8c:2d
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: adding address fe80::ebfe:c823:f85 7:9488
Dec 15 11:52:29 phoniebox avahi-daemon[268]: Joining mDNS multicast group on int erface wlan0.IPv6 with address fe80::ebfe:c823:f857:9488.
Dec 15 11:52:29 phoniebox avahi-daemon[268]: New relevant interface wlan0.IPv6 f or mDNS.
Dec 15 11:52:29 phoniebox avahi-daemon[268]: Registering new address record for fe80::ebfe:c823:f857:9488 on wlan0.*.
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: carrier lost
Dec 15 11:52:29 phoniebox dhcpcd[286]: wlan0: deleting address fe80::ebfe:c823:f 857:9488
Dec 15 11:52:29 phoniebox avahi-daemon[268]: Withdrawing address record for fe80 ::ebfe:c823:f857:9488 on wlan0.
Dec 15 11:52:29 phoniebox avahi-daemon[268]: Leaving mDNS multicast group on int erface wlan0.IPv6 with address fe80::ebfe:c823:f857:9488.
Dec 15 11:52:29 phoniebox avahi-daemon[268]: Interface wlan0.IPv6 no longer rele vant for mDNS.
Dec 15 11:52:30 phoniebox kernel: [ 33.521303] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
Dec 15 11:52:30 phoniebox dhcpcd[286]: wlan0: carrier acquired
Dec 15 11:52:30 phoniebox dhcpcd[286]: wlan0: connected to Access Point `Momo'
Dec 15 11:52:30 phoniebox dhcpcd[286]: wlan0: IAID eb:2c:8c:2d
Dec 15 11:52:30 phoniebox dhcpcd[286]: wlan0: adding address fe80::69d:2356:18:8 bfa
Dec 15 11:52:30 phoniebox avahi-daemon[268]: Joining mDNS multicast group on int erface wlan0.IPv6 with address fe80::69d:2356:18:8bfa.
Dec 15 11:52:30 phoniebox avahi-daemon[268]: New relevant interface wlan0.IPv6 f or mDNS.
Dec 15 11:52:30 phoniebox avahi-daemon[268]: Registering new address record for fe80::69d:2356:18:8bfa on wlan0.*.
Dec 15 11:52:31 phoniebox dhcpcd[286]: wlan0: rebinding lease of 192.168.178.214
Dec 15 11:52:31 phoniebox dhcpcd[286]: wlan0: probing address 192.168.178.214/24
Dec 15 11:52:31 phoniebox dhcpcd[286]: wlan0: soliciting an IPv6 router
Dec 15 11:52:32 phoniebox kernel: [ 35.018347] Bluetooth: Core ver 2.22
Dec 15 11:52:32 phoniebox kernel: [ 35.018517] NET: Registered protocol family 31
Dec 15 11:52:32 phoniebox kernel: [ 35.018529] Bluetooth: HCI device and conne ction manager initialized
Dec 15 11:52:32 phoniebox kernel: [ 35.018560] Bluetooth: HCI socket layer ini tialized
Dec 15 11:52:32 phoniebox kernel: [ 35.018579] Bluetooth: L2CAP socket layer i nitialized
Dec 15 11:52:32 phoniebox kernel: [ 35.018620] Bluetooth: SCO socket layer ini tialized
Dec 15 11:52:32 phoniebox kernel: [ 35.039654] Bluetooth: HCI UART driver ver 2.3
Dec 15 11:52:32 phoniebox kernel: [ 35.039677] Bluetooth: HCI UART protocol H4 registered
Dec 15 11:52:32 phoniebox kernel: [ 35.039792] Bluetooth: HCI UART protocol Th ree-wire (H5) registered
Dec 15 11:52:32 phoniebox kernel: [ 35.040087] Bluetooth: HCI UART protocol Br oadcom registered
Dec 15 11:52:32 phoniebox btuart[303]: bcm43xx_init
Dec 15 11:52:32 phoniebox btuart[303]: Flash firmware /lib/firmware/brcm/BCM4343 0A1.hcd
Dec 15 11:52:32 phoniebox btuart[303]: Set BDADDR UART: b8:27:eb:d3:73:d2
Dec 15 11:52:32 phoniebox btuart[303]: Set Controller UART speed to 3000000 bit/ s
Dec 15 11:52:32 phoniebox btuart[303]: Device setup complete
Dec 15 11:52:32 phoniebox systemd[1]: Starting Load/Save RF Kill Switch Status.. .
Dec 15 11:52:32 phoniebox systemd[1]: Started Configure Bluetooth Modems connect ed by UART.
Dec 15 11:52:32 phoniebox systemd[1]: Started Load/Save RF Kill Switch Status.
Dec 15 11:52:32 phoniebox systemd[1]: Starting Bluetooth service...
Dec 15 11:52:32 phoniebox systemd[1]: Created slice system-bthelper.slice.
Dec 15 11:52:33 phoniebox bluetoothd[423]: Bluetooth daemon 5.50
Dec 15 11:52:33 phoniebox systemd[1]: Started Bluetooth service.
Dec 15 11:52:33 phoniebox systemd[1]: Reached target Bluetooth.
Dec 15 11:52:33 phoniebox bluetoothd[423]: Starting SDP server
Dec 15 11:52:33 phoniebox systemd[1]: Started Raspberry Pi bluetooth helper.
Dec 15 11:52:33 phoniebox kernel: [ 36.001145] Bluetooth: BNEP (Ethernet Emula tion) ver 1.3
Dec 15 11:52:33 phoniebox kernel: [ 36.001162] Bluetooth: BNEP filters: protoc ol multicast
Dec 15 11:52:33 phoniebox kernel: [ 36.001193] Bluetooth: BNEP socket layer in itialized
Dec 15 11:52:33 phoniebox bluetoothd[423]: Bluetooth management interface 1.14 i nitialized
Dec 15 11:52:33 phoniebox dbus-daemon[269]: [system] Activating via systemd: ser vice name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.servi ce' requested by ':1.5' (uid=0 pid=423 comm="/usr/lib/bluetooth/bluetoothd ")
Dec 15 11:52:33 phoniebox bluetoothd[423]: Sap driver initialization failed.
Dec 15 11:52:33 phoniebox bluetoothd[423]: sap-server: Operation not permitted ( 1)
Dec 15 11:52:33 phoniebox bluetoothd[423]: Failed to set privacy: Rejected (0x0b )
Dec 15 11:52:33 phoniebox bthelper[426]: Raspberry Pi BDADDR already set
Dec 15 11:52:33 phoniebox systemd[1]: Starting Hostname Service...
Dec 15 11:52:33 phoniebox bthelper[426]: Changing power off succeeded
Dec 15 11:52:34 phoniebox dbus-daemon[269]: [system] Successfully activated serv ice 'org.freedesktop.hostname1'
Dec 15 11:52:34 phoniebox systemd[1]: Started Hostname Service.
Dec 15 11:52:34 phoniebox bthelper[426]: Changing power on succeeded
Dec 15 11:52:34 phoniebox bthelper[426]: [#033[0;93mCHG#033[0m] Controller B8:27 :EB:D3:73:D2 Powered: yes
Dec 15 11:52:34 phoniebox systemd[1]: bthelper@hci0.service: Succeeded.
Dec 15 11:52:36 phoniebox dhcpcd[286]: wlan0: leased 192.168.178.214 for 3600 se conds
Dec 15 11:52:36 phoniebox avahi-daemon[268]: Joining mDNS multicast group on int erface wlan0.IPv4 with address 192.168.178.214.
Dec 15 11:52:36 phoniebox dhcpcd[286]: wlan0: adding route to 192.168.178.0/24
Dec 15 11:52:36 phoniebox dhcpcd[286]: wlan0: adding default route via 192.168.1 78.1
Dec 15 11:52:36 phoniebox avahi-daemon[268]: New relevant interface wlan0.IPv4 f or mDNS.
Dec 15 11:52:36 phoniebox avahi-daemon[268]: Registering new address record for 192.168.178.214 on wlan0.IPv4.
Dec 15 11:52:37 phoniebox dhcpcd[286]: forked to background, child pid 529
Dec 15 11:52:37 phoniebox systemd[1]: Started dhcpcd on all interfaces.
Dec 15 11:52:37 phoniebox systemd[1]: Reached target Network.
Dec 15 11:52:37 phoniebox systemd[1]: Started Phoniebox Idle Watchdog Service.
Dec 15 11:52:37 phoniebox systemd[1]: Condition check resulted in fast remote fi le copy program daemon being skipped.
Dec 15 11:52:37 phoniebox systemd[1]: Started Phoniebox GPIO Control Service.
Dec 15 11:52:37 phoniebox systemd[1]: Starting OpenBSD Secure Shell server...
Dec 15 11:52:37 phoniebox systemd[1]: Starting Permit User Sessions...
Dec 15 11:52:38 phoniebox systemd[1]: Starting The PHP 7.3 FastCGI Process Manag er...
Dec 15 11:52:38 phoniebox systemd[1]: Reached target Network is Online.
Dec 15 11:52:38 phoniebox systemd[1]: Starting LSB: exim Mail Transport Agent...
Dec 15 11:52:38 phoniebox systemd[1]: Starting Lighttpd Daemon...
Dec 15 11:52:38 phoniebox systemd[1]: Starting Samba NMB Daemon...
Dec 15 11:52:38 phoniebox systemd[1]: Starting /etc/rc.local Compatibility...
Dec 15 11:52:38 phoniebox systemd[1]: Starting Music Player Daemon...
Dec 15 11:52:39 phoniebox systemd[1]: Started Phoniebox RFID-Reader Service.
Dec 15 11:52:39 phoniebox systemd[1]: Started Phoniebox MQTT Client Service.
Dec 15 11:52:39 phoniebox systemd[1]: systemd-rfkill.service: Succeeded.
Dec 15 11:52:40 phoniebox systemd[1]: Started Permit User Sessions.
Dec 15 11:52:40 phoniebox systemd[1]: Started /etc/rc.local Compatibility.
Dec 15 11:52:41 phoniebox systemd[1]: Started Getty on tty1.
Dec 15 11:52:41 phoniebox systemd[1]: Reached target Login Prompts.
Dec 15 11:52:41 phoniebox systemd[1]: Started OpenBSD Secure Shell server.
Dec 15 11:52:42 phoniebox systemd[1]: Started The PHP 7.3 FastCGI Process Manage r.
Dec 15 11:52:44 phoniebox dhcpcd[529]: wlan0: no IPv6 Routers available
Dec 15 11:52:45 phoniebox systemd[1]: Started Samba NMB Daemon.
Dec 15 11:52:45 phoniebox systemd[1]: Starting Samba SMB Daemon...
Dec 15 11:52:46 phoniebox PhonieboxGPIOControl[531]: INFO:root:['VolumeControl', 'PrevNextControl', 'PlayPause', 'Shutdown', 'Volume0', 'VolumeUp', 'VolumeDown' , 'NextSong', 'PrevSong', 'Halt']
Dec 15 11:52:46 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device VolumeCont rol not enabled
Dec 15 11:52:46 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device PrevNextCo ntrol not enabled
Dec 15 11:52:46 phoniebox PhonieboxGPIOControl[531]: INFO:root:adding GPIO-Devic e, PlayPause
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: /home/pi/RPi-Jukebox-RFID/c omponents/gpio_control/GPIODevices/simple_button.py:71: RuntimeWarning: This cha nnel is already in use, continuing anyway. Use GPIO.setwarnings(False) to disab le warnings.
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: GPIO.setup(self.pin, GPIO .IN, pull_up_down=self.pull_up_down)
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device Shutdown n ot enabled
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device Volume0 no t enabled
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: INFO:root:adding GPIO-Devic e, VolumeUp
Dec 15 11:52:47 phoniebox PhonieboxGPIOControl[531]: INFO:root:adding GPIO-Devic e, VolumeDown
Dec 15 11:52:48 phoniebox systemd[1]: Created slice User Slice of UID 1000.
Dec 15 11:52:48 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device NextSong n ot enabled
Dec 15 11:52:48 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device PrevSong n ot enabled
Dec 15 11:52:48 phoniebox PhonieboxGPIOControl[531]: INFO:root:Device Halt not e nabled
Dec 15 11:52:48 phoniebox PhonieboxGPIOControl[531]: INFO:root:Ready for taking actions
Dec 15 11:52:48 phoniebox systemd[1]: Starting User Runtime Directory /run/user/ 1000...
Dec 15 11:52:49 phoniebox systemd[1]: Started User Runtime Directory /run/user/1 000.
Dec 15 11:52:49 phoniebox systemd[1]: Starting User Manager for UID 1000...
Dec 15 11:52:49 phoniebox systemd[1]: systemd-fsckd.service: Succeeded.
Dec 15 11:52:51 phoniebox daemon_rfid_reader.py[558]: 2020-12-15 11:52:51,249 - root - INFO - Dir_PATH: /home/pi/RPi-Jukebox-RFID/scripts
Dec 15 11:52:52 phoniebox systemd[1]: Started Lighttpd Daemon.
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: Traceback (most recent cal l last):
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: File "/home/pi/RPi-Jukeb ox-RFID/scripts/daemon_mqtt_client.py", line 363, in <module>
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: client.tls_set(ca_cert s=mqttCA, certfile=mqttCert, keyfile=mqttKey)
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: File "/home/pi/.local/li b/python3.7/site-packages/paho/mqtt/client.py", line 819, in tls_set
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: context.load_cert_chai n(certfile, keyfile)
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: FileNotFoundError: [Errno 2] No such file or directory
Dec 15 11:52:53 phoniebox systemd[1]: phoniebox-mqtt-client.service: Main proces s exited, code=exited, status=1/FAILURE
Dec 15 11:52:53 phoniebox systemd[1]: phoniebox-mqtt-client.service: Failed with result 'exit-code'.
Dec 15 11:52:53 phoniebox exim4[546]: Starting MTA: exim4.
Dec 15 11:52:53 phoniebox systemd[1]: Started LSB: exim Mail Transport Agent.
Dec 15 11:52:53 phoniebox systemd[820]: Listening on GnuPG network certificate m anagement daemon.
Dec 15 11:52:53 phoniebox systemd[820]: Listening on GnuPG cryptographic agent a nd passphrase cache (restricted).
Dec 15 11:52:53 phoniebox systemd[820]: Listening on GnuPG cryptographic agent a nd passphrase cache (access for web browsers).
Dec 15 11:52:53 phoniebox systemd[820]: Reached target Timers.
Dec 15 11:52:53 phoniebox systemd[820]: Listening on GnuPG cryptographic agent ( ssh-agent emulation).
Dec 15 11:52:53 phoniebox systemd[820]: Reached target Paths.
Dec 15 11:52:53 phoniebox systemd[820]: Listening on GnuPG cryptographic agent a nd passphrase cache.
Dec 15 11:52:53 phoniebox systemd[820]: Reached target Sockets.
Dec 15 11:52:53 phoniebox systemd[820]: Reached target Basic System.
Dec 15 11:52:53 phoniebox systemd[1]: Started User Manager for UID 1000.
Dec 15 11:52:54 phoniebox systemd[1]: Started Session 1 of user pi.
Dec 15 11:52:54 phoniebox systemd[820]: Starting Music Player Daemon...
Dec 15 11:53:09 phoniebox systemd-timesyncd[229]: Synchronized to time server fo r the first time 195.50.171.101:123 (2.debian.pool.ntp.org).
Dec 15 11:53:10 phoniebox systemd[1]: Started Samba SMB Daemon.
Dec 15 11:53:18 phoniebox systemd[1]: phoniebox-mqtt-client.service: Service Res tartSec=10s expired, scheduling restart.
Dec 15 11:53:18 phoniebox systemd[1]: phoniebox-mqtt-client.service: Scheduled r estart job, restart counter is at 1.
Dec 15 11:53:18 phoniebox systemd[1]: Stopped Phoniebox MQTT Client Service.
Dec 15 11:53:18 phoniebox systemd[1]: Started Phoniebox MQTT Client Service.
Dec 15 11:53:19 phoniebox systemd[1]: systemd-hostnamed.service: Succeeded.
Dec 15 11:53:20 phoniebox mpd: zeroconf: No global port, disabling zeroconf
Dec 15 11:53:20 phoniebox systemd[1]: Started Music Player Daemon.
Dec 15 11:53:20 phoniebox systemd[1]: Starting Phoniebox Startup...
Dec 15 11:53:20 phoniebox bash[960]: AUDIOFOLDERSPATH="/home/pi/RPi-Jukebox-RFID /shared/audiofolders"
Dec 15 11:53:20 phoniebox bash[960]: PLAYLISTSFOLDERPATH="/home/pi/RPi-Jukebox-R FID/playlists"
Dec 15 11:53:20 phoniebox bash[960]: SECONDSWIPE="RESTART"
Dec 15 11:53:20 phoniebox bash[960]: SECONDSWIPEPAUSE="2"
Dec 15 11:53:20 phoniebox bash[960]: SECONDSWIPEPAUSECONTROLS="ON"
Dec 15 11:53:20 phoniebox bash[960]: AUDIOIFACENAME="Master"
Dec 15 11:53:20 phoniebox bash[960]: VOLUMEMANAGER="mpd"
Dec 15 11:53:20 phoniebox bash[960]: AUDIOVOLCHANGESTEP="3"
Dec 15 11:53:20 phoniebox bash[960]: AUDIOVOLMAXLIMIT="100"
Dec 15 11:53:20 phoniebox bash[960]: AUDIOVOLMINLIMIT="1"
Dec 15 11:53:20 phoniebox bash[960]: AUDIOVOLSTARTUP="20"
Dec 15 11:53:20 phoniebox bash[960]: VOLCHANGEIDLE="TRUE"
Dec 15 11:53:20 phoniebox bash[960]: IDLETIMESHUTDOWN="0"
Dec 15 11:53:20 phoniebox bash[960]: POWEROFFCMD="sudo poweroff"
Dec 15 11:53:20 phoniebox bash[960]: SHOWCOVER="ON"
Dec 15 11:53:20 phoniebox bash[960]: READWLANIPYN="OFF"
Dec 15 11:53:20 phoniebox bash[960]: EDITION="classic"
Dec 15 11:53:20 phoniebox bash[960]: LANG="de-DE"
Dec 15 11:53:20 phoniebox bash[960]: VERSION="2.2 - 305325d - master"
Dec 15 11:53:20 phoniebox bash[960]: CMDVOLUP=""
Dec 15 11:53:20 phoniebox bash[960]: CMDVOLDOWN=""
Dec 15 11:53:20 phoniebox bash[960]: CMDNEXT=""
Dec 15 11:53:20 phoniebox bash[960]: CMDPREV=""
Dec 15 11:53:20 phoniebox bash[960]: CMDREWIND=""
Dec 15 11:53:20 phoniebox bash[960]: CMDSEEKFORW=""
Dec 15 11:53:20 phoniebox bash[960]: CMDSEEKBACK=""
Dec 15 11:53:20 phoniebox bash[960]: 20 is the mpd startup volume
Dec 15 11:53:23 phoniebox bash[960]: OK MPD 0.21.4
Dec 15 11:53:23 phoniebox bash[960]: OK
Dec 15 11:53:23 phoniebox bash[960]: 6553 is the mpg123 startup volume
Dec 15 11:53:23 phoniebox bash[960]: High Performance MPEG 1.0/2.0/2.5 Audio Pla yer for Layers 1, 2 and 3
Dec 15 11:53:23 phoniebox bash[960]: #011version 1.25.10; written and copyright by Michael Hipp and others
Dec 15 11:53:23 phoniebox bash[960]: #011free software (LGPL) without any warran ty but with best wishes
Dec 15 11:53:23 phoniebox bash[960]: Cannot connect to server socket err = No su ch file or directory
Dec 15 11:53:23 phoniebox bash[960]: Cannot connect to server request channel
Dec 15 11:53:23 phoniebox bash[960]: jack server is not running or cannot be sta rted
Dec 15 11:53:23 phoniebox bash[960]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec 15 11:53:23 phoniebox bash[960]: JackShmReadWritePtr::~JackShmReadWritePtr - Init not done for -1, skipping unlock
Dec 15 11:53:23 phoniebox bash[960]: /home/pi/RPi-Jukebox-RFID/scripts/startup-s cripts.sh: line 42: 1017 Segmentation fault /usr/bin/mpg123 -f -${mpgvolum e} /home/pi/RPi-Jukebox-RFID/shared/startupsound.mp3
Dec 15 11:53:23 phoniebox bash[960]: RADIO BOB! Nordrhein-Westfalen (NRW)
Dec 15 11:53:23 phoniebox bash[960]: [playing] #1/1 0:02/0:00 (0%)
Dec 15 11:53:23 phoniebox bash[960]: Updating DB (#1) ...
Dec 15 11:53:23 phoniebox bash[960]: volume: 20% repeat: off random: off s ingle: off consume: off
Dec 15 11:53:23 phoniebox mpd: update: updating WeißtDuEigentlichWieLiebIchDichH ab/Weißt du eigentlich wie lieb ich dich hab.mp3
Dec 15 11:53:23 phoniebox systemd[1]: Started Phoniebox Startup.
Dec 15 11:53:23 phoniebox mpd: update: updating Janosch/Post für den Tiger - Jan oschs Traumstunde.mp3
Dec 15 11:53:23 phoniebox systemd[1]: Reached target Multi-User System.
Dec 15 11:53:23 phoniebox systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec 15 11:53:23 phoniebox daemon_mqtt_client.py[945]: Traceback (most recent cal l last):
Dec 15 11:53:23 phoniebox daemon_mqtt_client.py[945]: File "/home/pi/RPi-Jukeb ox-RFID/scripts/daemon_mqtt_client.py", line 363, in <module>
Dec 15 11:53:23 phoniebox daemon_mqtt_client.py[945]: client.tls_set(ca_cert s=mqttCA, certfile=mqttCert, keyfile=mqttKey)
Dec 15 11:53:23 phoniebox daemon_mqtt_client.py[945]: File "/home/pi/.local/li b/python3.7/site-packages/paho/mqtt/client.py", line 819, in tls_set
Dec 15 11:53:24 phoniebox daemon_mqtt_client.py[945]: context.load_cert_chai n(certfile, keyfile)
Dec 15 11:53:24 phoniebox daemon_mqtt_client.py[945]: FileNotFoundError: [Errno 2] No such file or directory
Dec 15 11:53:24 phoniebox systemd[1]: systemd-update-utmp-runlevel.service: Succ eeded.
Dec 15 11:53:24 phoniebox systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec 15 11:53:24 phoniebox systemd[1]: Startup finished in 4.191s (kernel) + 1min 8.003s (userspace) = 1min 12.194s.
Dec 15 11:53:24 phoniebox systemd[1]: phoniebox-mqtt-client.service: Main proces s exited, code=exited, status=1/FAILURE
Dec 15 11:53:24 phoniebox systemd[1]: phoniebox-mqtt-client.service: Failed with result 'exit-code'.
Dec 15 11:53:24 phoniebox mpd: exception: Failed to bind to '[::1]:6600'
Dec 15 11:53:24 phoniebox mpd: exception: nested: Failed to bind socket: Address already in use
Dec 15 11:53:25 phoniebox systemd[820]: mpd.service: Main process exited, code=e xited, status=1/FAILURE
Dec 15 11:53:25 phoniebox systemd[820]: mpd.service: Failed with result 'exit-co de'.
Dec 15 11:53:25 phoniebox systemd[820]: Failed to start Music Player Daemon.
Dec 15 11:53:25 phoniebox systemd[820]: Reached target Default.
Dec 15 11:53:25 phoniebox systemd[820]: Startup finished in 19.537s.
Dec 15 11:53:34 phoniebox systemd[1]: phoniebox-mqtt-client.service: Service Res tartSec=10s expired, scheduling restart.
Dec 15 11:53:34 phoniebox systemd[1]: phoniebox-mqtt-client.service: Scheduled r estart job, restart counter is at 2.
Dec 15 11:53:34 phoniebox systemd[1]: Stopped Phoniebox MQTT Client Service.
Dec 15 11:53:34 phoniebox systemd[1]: Started Phoniebox MQTT Client Service.
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: Traceback (most recent ca ll last):
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: File "/home/pi/RPi-Juke box-RFID/scripts/daemon_mqtt_client.py", line 363, in <module>
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: client.tls_set(ca_cer ts=mqttCA, certfile=mqttCert, keyfile=mqttKey)
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: File "/home/pi/.local/l ib/python3.7/site-packages/paho/mqtt/client.py", line 819, in tls_set
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: context.load_cert_cha in(certfile, keyfile)
Dec 15 11:53:36 phoniebox daemon_mqtt_client.py[1054]: FileNotFoundError: [Errno 2] No such file or directory
Dec 15 11:53:36 phoniebox systemd[1]: phoniebox-mqtt-client.service: Main proces s exited, code=exited, status=1/FAILURE
Dec 15 11:53:36 phoniebox systemd[1]: phoniebox-mqtt-client.service: Failed with result 'exit-code'.
Dec 15 11:53:40 phoniebox systemd[1]: Started Session 3 of user pi.
Dec 15 11:53:46 phoniebox systemd[1]: phoniebox-mqtt-client.service: Service Res tartSec=10s expired, scheduling restart.
Dec 15 11:53:46 phoniebox systemd[1]: phoniebox-mqtt-client.service: Scheduled r estart job, restart counter is at 3.
Dec 15 11:53:46 phoniebox systemd[1]: Stopped Phoniebox MQTT Client Service.
Dec 15 11:53:46 phoniebox systemd[1]: Started Phoniebox MQTT Client Service.
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: Traceback (most recent ca ll last):
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: File "/home/pi/RPi-Juke box-RFID/scripts/daemon_mqtt_client.py", line 363, in <module>
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: client.tls_set(ca_cer ts=mqttCA, certfile=mqttCert, keyfile=mqttKey)
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: File "/home/pi/.local/l ib/python3.7/site-packages/paho/mqtt/client.py", line 819, in tls_set
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: context.load_cert_cha in(certfile, keyfile)
Dec 15 11:53:48 phoniebox daemon_mqtt_client.py[1111]: FileNotFoundError: [Errno 2] No such file or directory
Dec 15 11:53:48 phoniebox systemd[1]: phoniebox-mqtt-client.service: Main proces s exited, code=exited, status=1/FAILURE
Dec 15 11:53:48 phoniebox systemd[1]: phoniebox-mqtt-client.service: Failed with result 'exit-code'.
Dec 15 11:53:53 phoniebox systemd[1]: phoniebox-idle-watchdog.service: Succeeded .
Dec 15 11:53:58 phoniebox systemd[1]: phoniebox-mqtt-client.service: Service Res tartSec=10s expired, scheduling restart.
Dec 15 11:53:58 phoniebox systemd[1]: phoniebox-mqtt-client.service: Scheduled r estart job, restart counter is at 4.
Dec 15 11:53:58 phoniebox systemd[1]: Stopped Phoniebox MQTT Client Service.
Dec 15 11:53:58 phoniebox systemd[1]: Started Phoniebox MQTT Client Service.cat ~/RPi-Jukebox-RFID/settings/gpio_settings.ini
[DEFAULT]
enabled: False
[VolumeControl]
enabled: False
Type: TwoButtonControl ;or RotaryEncoder
PinUp: 5
PinDown: 6
pull_up: True
hold_time: 0.3
hold_repeat: True
timeBase: 0.1 ;only for RotaryEncoder
functionCallDown: functionCallVolD
functionCallUp: functionCallVolU
functionCallTwoButtons: functionCallVol0 ;only for TwoButtonControl
[PrevNextControl]
enabled: False
Type: TwoButtonControl
Pin1: 22
Pin2: 23
functionCall1: functionCallPlayerPrev
functionCall2: functionCallPlayerNext
functionCallTwoButtons: None
pull_up: True
hold_time: 0.3
hold_repeat: False
[PlayPause]
enabled: True
Type: Button
Pin: 5
pull_up: True
hold_time: 0.3
functionCall: functionCallPlayerPause
[Shutdown]
enabled: False
Type: Button
Pin: 3
pull_up: True
hold_time: 2
functionCall: functionCallShutdown
[Volume0]
enabled: False
Type: Button
Pin: 17
pull_up: True
hold_time: 0.3
functionCall: functionCallVol0
[VolumeUp]
enabled: True
Type: Button
Pin: 8
pull_up: True
hold_time: 0.3
hold_repeat: True
functionCall: functionCallVolU
[VolumeDown]
enabled: True
Type: Button
Pin: 7
pull_up: True
hold_time: 0.3
hold_repeat: True
functionCall: functionCallVolD
[NextSong]
enabled: False
Type: Button
Pin: 21
pull_up: True
hold_time: 0.3
functionCall: functionCallPlayerNext
[PrevSong]
enabled: False
Type: Button
Pin: 20
pull_up: True
hold_time: 0.3
functionCall: functionCallPlayerPrev
[Halt]
enabled: False
Type: Button
Pin: 21
pull_up: True
hold_time: 0.3
functionCall: functionCallPlayerPauseForce
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: Traceback (most recent call last):
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: File "/home/pi/RPi-Jukebox-RFID/scripts/daemon_mqtt_client.py", line 363, in <module>
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: client.tls_set(ca_certs=mqttCA, certfile=mqttCert, keyfile=mqttKey)
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: File "/home/pi/.local/lib/python3.7/site-packages/paho/mqtt/client.py", line 819, in tls_set
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: context.load_cert_chain(certfile, keyfile)
Dec 15 11:52:52 phoniebox daemon_mqtt_client.py[561]: FileNotFoundError: [Errno2] No such file or directoryLooks like a file is missing.
have you configured authentication for password here https://github.com/MiczFlor/RPi-Jukebox-RFID/blob/6dc047ab1959de41c1aa732536dd0ed92b0d0dad/components/smart-home-automation/MQTT-protocol/daemon_mqtt_client.py#L21
or for certificate based authentication here https://github.com/MiczFlor/RPi-Jukebox-RFID/blob/6dc047ab1959de41c1aa732536dd0ed92b0d0dad/components/smart-home-automation/MQTT-protocol/daemon_mqtt_client.py#L23
Please check also https://github.com/MiczFlor/RPi-Jukebox-RFID/blob/develop/components/smart-home-automation/MQTT-protocol/README.md
s-martin
commented
3 years ago Please make sure that all needed buttons are enabled and you need to use the GPIO number! for Pin.
s-martin
commented
3 years ago Please check also https://github.com/MiczFlor/RPi-Jukebox-RFID/discussions/865?converting=1#discussioncomment-211905 for MQTT
phlowsec
commented
3 years ago Hi, I had a similar issue, after upgrading. For me it helped to change in the components/gpio_control/gpio_control.py the location of the gpio_settings.init (should be line 101) to ~/RPi-Jukebox-RFID/settings/gpio_settings.ini. Perhaps it helps.
s-martin
commented
3 years ago Hi, I had a similar issue, after upgrading. For me it helped to change in the components/gpio_control/gpio_control.py the location of the gpio_settings.init (should be line 101) to ~/RPi-Jukebox-RFID/settings/gpio_settings.ini. Perhaps it helps.
This should already be fixed since 2.2
s-martin
commented
2 years ago Closing, please reopen if the issue still exists.
Here is what i use: Raspberry Pi Zero W HifiBerry Miniamp Neuftech RFID Reader Some LEDs, some push buttons
So before the update, the LED blinks at startup, and i could controll with the push buttons VolUp/Dow, Play/Pause, Shutdown (via Shim) and Prev/Next song. Also i can controll the whole phoniebox via mqtt.
So, here is what i did: Update from 2.0rc7 to 2.2. with the on-line script First: To keep all songs, folders.... FAIL. And yes I clicked YES on the question: Do you want to take over old settings, so also on the questions after that where it was about the RFID settings etc.. I have read the instructions for the new GPIO procedure that I have to redo the settings, here I have entered my GPIO pins. So also in the MQTT settings file username and password for MQTT.
What happend now? So you can start the box, the LEDs flash, then when the software is started, the LEDs go off, was not so before. You can then start music via the web interface, which is also played, volume up and down can also be done via the software, but exactly nothing happens via the buttons.
MQTT does not connect either.
So, what log do I need to get so we can figure out if this is an error between the keyboard and the chair or in the system?