MFRC522 NO read

[taylorcjohnston]

hey all . cant for the life of me get future3 to read from the MFRC522. As far as I know pins are correct. I run a separate python script to test the reader and the script reads the cards just fine,

Install: bullseye
Ras. lite 32 legacy.

i also have buttons on GPIO pins as well, They seem to work.

Is there something outside of the general install that i should be doing?

[s-martin]

Please check the logs

https://github.com/MiczFlor/RPi-Jukebox-RFID/blob/future3/develop/documentation/builders/troubleshooting.md#the-short-answer

[taylorcjohnston]

no errors in error log.

and app log shows: For reader config key 'read_00': loading module 'rc522_spi'
Initializing reader 'MFRC522 via SPI'
Using IRQ pin 24 for interrupt-driven card detection
Using legacy_mode = 'False'
Start listening!

[taylorcjohnston]

25.02.2026 21:26:08 - 41:daemon.py - jb.daemon - MainThread - INFO - Starting Jukebox Daemon (Version 3.6.0-alpha)
25.02.2026 21:26:08 - 44:daemon.py - jb.daemon - MainThread - INFO - Git state: cd45538 [2025-11-25] Merge pull request #2557 from astnerdev/future3/fix-rc522-legcy (HEAD -> future3/develop, origin/future3/develop) [v3.5.3-67-gcd455384]
25.02.2026 21:26:08 - 318:cfghandler.py - jb.cfghandler - MainThread - INFO - (jukebox) Loading yaml file '/home/pi/RPi-Jukebox-RFID/shared/settings/jukebox.yaml'
25.02.2026 21:26:09 - 230:cfghandler.py - jb.cfghandler - MainThread - DEBUG - (jukebox) Replacing current config data
25.02.2026 21:26:09 - 53:daemon.py - jb.daemon - MainThread - INFO - Welcome to Jukebox
25.02.2026 21:26:09 - 54:daemon.py - jb.daemon - MainThread - INFO - Time of start: Wed Feb 25 21:26:08 2026
25.02.2026 21:26:09 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.publishing' as 'publishing'
25.02.2026 21:26:09 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.publishing.republish' as 'publishing.republish' (<class 'function'>)
25.02.2026 21:26:09 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling publishing.initialize()
25.02.2026 21:26:09 - 225:server.py - jb.pub.server - MainThread - DEBUG - PublishServer initialized (Pyzmq version: 25.1.2; ZMQ version: 4.3.5; has draft API: True)
25.02.2026 21:26:09 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.volume' as 'volume'
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.toggle_output
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.get_outputs
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.publish_volume
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.publish_outputs
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.set_volume
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.get_volume
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.change_volume
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.get_mute
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.mute
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.set_output
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.set_soft_max_volume
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PulseVolumeControl.get_soft_max_volume
25.02.2026 21:26:10 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling volume.initialize()
25.02.2026 21:26:10 - 250:init.py - jb.pulse - PulseMon - INFO - Start Pulse Monitor Thread
25.02.2026 21:26:10 - 596:init.py - jb.pulse - MainThread - WARNING - The primary audio output configuration is incomplete. Creating an entry from the current default sink 'description='Built-in Audio Analog Stereo', index=0, mute=0, name='alsa_output.platform-bcm2835_audio.analog-stereo', channels=2, volumes=[40% 40%]'
25.02.2026 21:26:10 - 613:init.py - jb.pulse - MainThread - INFO - Ignoring secondary audio output configuration because it is missing or incomplete
25.02.2026 21:26:10 - 352:init.py - jb.pulse - MainThread - DEBUG - Configured audio sinks: [PaSink(alias='Built-in speakers', pulse_sink_name='alsa_output.platform-bcm2835_audio.analog-stereo', volume_limit=100)]
25.02.2026 21:26:10 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.jingle' as 'jingle'
25.02.2026 21:26:10 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.jingle.play' as 'jingle.play' (<class 'function'>)
25.02.2026 21:26:10 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.jingle.play_startup' as 'jingle.play_startup' (<class 'function'>)
25.02.2026 21:26:10 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.jingle.play_shutdown' as 'jingle.play_shutdown' (<class 'function'>)
25.02.2026 21:26:10 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling jingle.initialize()
25.02.2026 21:26:10 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.jingle.alsawave' as 'jingle.alsawave'
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging AlsaWave.play
25.02.2026 21:26:10 - 318:plugs.py - jb.plugin - MainThread - DEBUG - Decorating class components.jingle.alsawave.AlsaWave for auto-assignment into package 'jingle.alsawave'
25.02.2026 21:26:10 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling jingle.alsawave.initialize()
25.02.2026 21:26:10 - 337:plugs.py - jb.plugin - MainThread - DEBUG - Instantiating decorated class 'components.jingle.alsawave.AlsaWave' and register as 'jingle.alsawave.alsawave'
25.02.2026 21:26:10 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.jingle.alsawave.AlsaWave' as 'jingle.alsawave.alsawave'
25.02.2026 21:26:10 - 24:init.py - jb.jingle - MainThread - DEBUG - Register 'wav' in <class 'components.jingle.JingleFactory'>.
25.02.2026 21:26:10 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.jingle.jinglemp3' as 'jingle.jinglemp3'
25.02.2026 21:26:10 - 318:plugs.py - jb.plugin - MainThread - DEBUG - Decorating class components.jingle.jinglemp3.JingleMp3Play for auto-assignment into package 'jingle.jinglemp3'
25.02.2026 21:26:10 - 344:plugs.py - jb.plugin - MainThread - DEBUG - Auto-tagging all methods and functions of class 'components.jingle.jinglemp3.JingleMp3Play'
25.02.2026 21:26:10 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling jingle.jinglemp3.initialize()
25.02.2026 21:26:10 - 337:plugs.py - jb.plugin - MainThread - DEBUG - Instantiating decorated class 'components.jingle.jinglemp3.JingleMp3Play' and register as 'jingle.jinglemp3.jinglemp3'
25.02.2026 21:26:10 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.jingle.jinglemp3.JingleMp3Play' as 'jingle.jinglemp3.jinglemp3'
25.02.2026 21:26:10 - 24:init.py - jb.jingle - MainThread - DEBUG - Register 'mp3' in <class 'components.jingle.JingleFactory'>.
25.02.2026 21:26:10 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.playermpd' as 'player'
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.start
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.cancel
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.toggle
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.trigger
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.is_alive
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.get_timeout
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.set_timeout
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.publish
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericTimerClass.get_state
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericEndlessTimerClass.get_state
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericMultiTimerClass.start
25.02.2026 21:26:10 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging GenericMultiTimerClass.get_state
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_player_type_and_version
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.update
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.update_wait
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.play
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.stop
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.pause
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.prev
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.next
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.seek
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.rewind
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.replay
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.toggle
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.replay_if_stopped
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.shuffle
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.repeat
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_current_song
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.map_filename_to_playlist_pos
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.remove
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.move
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.play_single
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.resume
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.play_card
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_single_coverart
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_album_coverart
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.flush_coverart_cache
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_folder_content
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.play_folder
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.play_album
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.queue_load
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.playerstatus
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.playlistinfo
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.list_all_dirs
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.list_albums
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.list_songs_by_artist_and_album
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging PlayerMPD.get_song_by_url
25.02.2026 21:26:12 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling player.initialize()
25.02.2026 21:26:12 - 194:init.py - jb.PlayerMPD - MainThread - INFO - Connected to MPD Version: 0.22.4
25.02.2026 21:26:12 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - mpd.timer_status: State = {'enabled': False, 'wait_seconds_per_iteration': 0.25, 'type': 'GenericEndlessTimerClass'}
25.02.2026 21:26:12 - 312:multitimer.py - jb.multitimers - mpd.timer_status - DEBUG - mpd.timer_status: State = {'enabled': True, 'wait_seconds_per_iteration': 0.25, 'type': 'GenericEndlessTimerClass'}
25.02.2026 21:26:12 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.playermpd.PlayerMPD' as 'player.ctrl'
25.02.2026 21:26:12 - 24:init.py - jb.player - MainThread - DEBUG - MPD music lib path = /home/pi/RPi-Jukebox-RFID/shared/audiofolders; from /home/pi/.config/mpd/mpd.conf
25.02.2026 21:26:12 - 787:init.py - jb.PlayerMPD - MainThread - INFO - Change user rights for /home/pi/RPi-Jukebox-RFID/shared/audiofolders
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.rfid.cards' as 'cards'
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.list_cards' as 'cards.list_cards' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.delete_card' as 'cards.delete_card' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.register_card' as 'cards.register_card' (<class 'function'>)
25.02.2026 21:26:12 - 132:multitimer.py - jb.multitimers - mpd.timer_status - DEBUG - Start timer 'mpd.timer_status in endless mode
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.register_card_custom' as 'cards.register_card_custom' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.load_card_database' as 'cards.load_card_database' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.rfid.cards.save_card_database' as 'cards.save_card_database' (<class 'function'>)
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.rfid.reader' as 'rfid'
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.timers' as 'timers'
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging IdleShutdownTimer.start
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging IdleShutdownTimer.cancel
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging IdleShutdownTimer.get_state
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging VolumeFadoutAndShutdown.start
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging VolumeFadoutAndShutdown.cancel
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging VolumeFadoutAndShutdown.is_alive
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging VolumeFadoutAndShutdown.get_state
25.02.2026 21:26:12 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging VolumeFadoutAndShutdown.get_config
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.hostif.linux' as 'host'
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.shutdown' as 'host.shutdown' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.reboot' as 'host.reboot' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.jukebox_is_service' as 'host.jukebox_is_service' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.is_any_jukebox_service_active' as 'host.is_any_jukebox_service_active' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.restart_service' as 'host.restart_service' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.get_disk_usage' as 'host.get_disk_usage' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.get_cpu_temperature' as 'host.get_cpu_temperature' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.publish_cpu_temperature' as 'host.publish_cpu_temperature' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.get_ip_address' as 'host.get_ip_address' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.say_my_ip' as 'host.say_my_ip' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.wlan_disable_power_down' as 'host.wlan_disable_power_down' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.get_autohotspot_status' as 'host.get_autohotspot_status' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.stop_autohotspot' as 'host.stop_autohotspot' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.start_autohotspot' as 'host.start_autohotspot' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.hdmi_power_down' as 'host.hdmi_power_down' (<class 'function'>)
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.hostif.linux.get_throttled' as 'host.get_throttled' (<class 'function'>)
25.02.2026 21:26:12 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling host.initialize()
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.controls.bluetooth_audio_buttons' as 'bluetooth_audio_buttons'
25.02.2026 21:26:12 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.controls.bluetooth_audio_buttons.activate' as 'bluetooth_audio_buttons.activate' (<class 'function'>)
25.02.2026 21:26:12 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling bluetooth_audio_buttons.initialize()
25.02.2026 21:26:12 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.gpio.gpioz.plugin' as 'gpio'
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.gpio.gpioz.plugin.on' as 'gpio.on' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.gpio.gpioz.plugin.off' as 'gpio.off' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.gpio.gpioz.plugin.set_value' as 'gpio.set_value' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.gpio.gpioz.plugin.flash' as 'gpio.flash' (<class 'function'>)
25.02.2026 21:26:13 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling gpio.initialize()
25.02.2026 21:26:13 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.synchronisation.rfidcards' as 'sync_rfidcards'
25.02.2026 21:26:13 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging SyncRfidcards.sync_change_on_rfid_scan
25.02.2026 21:26:13 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging SyncRfidcards.sync_all
25.02.2026 21:26:13 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging SyncRfidcards.sync_card_database
25.02.2026 21:26:13 - 459:plugs.py - jb.plugin - MainThread - DEBUG - Tagging SyncRfidcards.sync_folder
25.02.2026 21:26:13 - 570:plugs.py - jb.plugin - MainThread - DEBUG - Package load initializer: calling sync_rfidcards.initialize()
25.02.2026 21:26:13 - 78:init.py - jb.sync_rfidcards - MainThread - INFO - Sync RFID cards deactivated
25.02.2026 21:26:13 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.synchronisation.rfidcards.SyncRfidcards' as 'sync_rfidcards.ctrl'
25.02.2026 21:26:13 - 545:plugs.py - jb.plugin - MainThread - INFO - Loading plugin 'components.misc' as 'misc'
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.rpc_cmd_help' as 'misc.rpc_cmd_help' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_all_loaded_packages' as 'misc.get_all_loaded_packages' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_all_failed_packages' as 'misc.get_all_failed_packages' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_start_time' as 'misc.get_start_time' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_log_debug' as 'misc.get_log_debug' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_log_error' as 'misc.get_log_error' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_version' as 'misc.get_version' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_git_state' as 'misc.get_git_state' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.empty_rpc_call' as 'misc.empty_rpc_call' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.get_app_settings' as 'misc.get_app_settings' (<class 'function'>)
25.02.2026 21:26:13 - 278:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting function 'components.misc.set_app_settings' as 'misc.set_app_settings' (<class 'function'>)
25.02.2026 21:26:13 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling volume.finalize()
25.02.2026 21:26:13 - 455:init.py - jb.pulse - MainThread - INFO - Audio sink is now 'Built-in speakers' :: 'alsa_output.platform-bcm2835_audio.analog-stereo'
25.02.2026 21:26:13 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.volume.PulseVolumeControl' as 'volume.ctrl'
25.02.2026 21:26:13 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling jingle.finalize()
25.02.2026 21:26:13 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: jingle.play_startup(args=(), kwargs=None)
25.02.2026 21:26:13 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling cards.finalize()
25.02.2026 21:26:13 - 695:plugs.py - jb.plugin.call - StartJingle - DEBUG - Calling: volume.ctrl.get_volume(args=(), kwargs=None)
25.02.2026 21:26:13 - 318:cfghandler.py - jb.cfghandler - MainThread - INFO - (cards) Loading yaml file '../../shared/settings/cards.yaml'
25.02.2026 21:26:13 - 230:cfghandler.py - jb.cfghandler - MainThread - DEBUG - (cards) Replacing current config data
25.02.2026 21:26:13 - 695:plugs.py - jb.plugin.call - StartJingle - DEBUG - Calling: volume.ctrl.set_volume(args=[30], kwargs=None)
25.02.2026 21:26:13 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling rfid.finalize()
25.02.2026 21:26:13 - 318:cfghandler.py - jb.cfghandler - MainThread - INFO - (rfid) Loading yaml file '../../shared/settings/rfid.yaml'
25.02.2026 21:26:13 - 230:cfghandler.py - jb.cfghandler - MainThread - DEBUG - (rfid) Replacing current config data
25.02.2026 21:26:13 - 101:init.py - jb.rfid(read_00) - MainThread - INFO - For reader config key 'read_00': loading module 'rc522_spi'
25.02.2026 21:26:13 - 42:init.py - jb.jingle - StartJingle - DEBUG - Auto: 'wav' from ../../resources/audio/startupsound.wav.
25.02.2026 21:26:13 - 50:init.py - jb.alsaif - StartJingle - DEBUG - Playing wave file
25.02.2026 21:26:13 - 116:init.py - jb.rfid(read_00) - MainThread - DEBUG - Decoded removal action: player.ctrl.pause()
25.02.2026 21:26:13 - 139:init.py - jb.rfid(read_00) - read_00Thread - DEBUG - Start listening!
25.02.2026 21:26:13 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling timers.finalize()
25.02.2026 21:26:13 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - timers.timer_shutdown: State = {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}
25.02.2026 21:26:13 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'jukebox.multitimer.GenericTimerClass' as 'timers.timer_shutdown'
25.02.2026 21:26:13 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - timers.timer_stop_player: State = {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}
25.02.2026 21:26:13 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'jukebox.multitimer.GenericTimerClass' as 'timers.timer_stop_player'
25.02.2026 21:26:13 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.timers.volume_fadeout_shutdown_timer.VolumeFadoutAndShutdown' as 'timers.timer_fade_volume'
25.02.2026 21:26:13 - 53:idle_shutdown_timer.py - jb.timers.idle_shutdown_timer - MainThread - INFO - disabling idle shutdown timer; set timers.idle_shutdown.timeout_sec to at least 60 seconds to enable
25.02.2026 21:26:13 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - timers.private_timer_idle_shutdown: State = {'enabled': False, 'wait_seconds_per_iteration': 0, 'remaining_seconds_current_iteration': 0, 'remaining_seconds': 0, 'iterations': 1, 'type': 'GenericMultiTimerClass'}
25.02.2026 21:26:14 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'jukebox.multitimer.GenericMultiTimerClass' as 'timers.private_timer_idle_shutdown'
25.02.2026 21:26:14 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: player.ctrl.playerstatus(args=(), kwargs=None)
25.02.2026 21:26:14 - 114:idle_shutdown_timer.py - jb.timers.idle_shutdown_timer - MainThread - DEBUG - Started IdleCheck with initial state: {'repeat': '0', 'random': '0', 'single': '0', 'consume': '0', 'partition': 'default', 'playlist': '1', 'playlistlength': '0', 'mixrampdb': '0.000000', 'state': 'stop'}
25.02.2026 21:26:14 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - timers.private_timer_idle_check: State = {'enabled': False, 'wait_seconds_per_iteration': 10, 'type': 'GenericEndlessTimerClass'}
25.02.2026 21:26:14 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'jukebox.multitimer.GenericEndlessTimerClass' as 'timers.private_timer_idle_check'
25.02.2026 21:26:14 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'components.timers.idle_shutdown_timer.IdleShutdownTimer' as 'timers.timer_idle_shutdown'
25.02.2026 21:26:14 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling host.finalize()
25.02.2026 21:26:14 - 312:multitimer.py - jb.multitimers - MainThread - DEBUG - host.timer.cputemp: State = {'enabled': False, 'wait_seconds_per_iteration': 10, 'type': 'GenericEndlessTimerClass'}
25.02.2026 21:26:14 - 290:plugs.py - jb.plugin - MainThread - DEBUG - Enlisting an instance of class 'jukebox.multitimer.GenericEndlessTimerClass' as 'host.timer_temperature'
25.02.2026 21:26:14 - 312:multitimer.py - jb.multitimers - host.timer.cputemp - DEBUG - host.timer.cputemp: State = {'enabled': True, 'wait_seconds_per_iteration': 10, 'type': 'GenericEndlessTimerClass'}
25.02.2026 21:26:14 - 620:plugs.py - jb.plugin - MainThread - DEBUG - Package load finalizer: calling gpio.finalize()
25.02.2026 21:26:14 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: misc.get_all_loaded_packages(args=(), kwargs=None)
25.02.2026 21:26:14 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: misc.get_all_failed_packages(args=(), kwargs=None)
25.02.2026 21:26:14 - 144:daemon.py - jb.daemon - MainThread - INFO - Loaded plugins: publishing, volume, jingle, jingle.alsawave, jingle.jinglemp3, player, cards, rfid, timers, host, bluetooth_audio_buttons, gpio, sync_rfidcards, misc
25.02.2026 21:26:14 - 64:server.py - jb.rpc.server - MainThread - INFO - Init RPC Server (Pyzmq version: 25.1.2; ZMQ version: 4.3.5; has draft API: True)
25.02.2026 21:26:14 - 72:server.py - jb.rpc.server - MainThread - DEBUG - Connected to address 'inproc://JukeBoxRpcServer'
25.02.2026 21:26:14 - 78:server.py - jb.rpc.server - MainThread - DEBUG - Connected to address 'tcp://:5555'
25.02.2026 21:26:14 - 84:server.py - jb.rpc.server - MainThread - DEBUG - Connected to address 'ws://:5556'
25.02.2026 21:26:14 - 89:server.py - jb.rpc.server - MainThread - INFO - All socket connections initialized
25.02.2026 21:26:14 - 229:daemon.py - jb.daemon - MainThread - INFO - Start-up time: 4532.682 ms
25.02.2026 21:26:14 - 101:server.py - jb.rpc.server - MainThread - INFO - RPC Servers started
25.02.2026 21:26:14 - 132:multitimer.py - jb.multitimers - host.timer.cputemp - DEBUG - Start timer 'host.timer.cputemp in endless mode
25.02.2026 21:26:14 - 19:readerbase.py - jb.rfid.522(read_00) - read_00Thread - INFO - Initializing reader 'MFRC522 via SPI' from '/home/pi/RPi-Jukebox-RFID/src/jukebox/components/rfid/hardware/rc522_spi/rc522_spi.py'
25.02.2026 21:26:14 - 20:readerbase.py - jb.rfid.522(read_00) - read_00Thread - DEBUG - Reader object is <components.rfid.hardware.rc522_spi.rc522_spi.ReaderClass object at 0x613bf958> for reader config key 'read_00'
25.02.2026 21:26:14 - 83:rc522_spi.py - jb.rfid.522(read_00) - read_00Thread - INFO - Using IRQ pin 24 for interrupt-driven card detection
25.02.2026 21:26:14 - 90:rc522_spi.py - jb.rfid.522(read_00) - read_00Thread - INFO - Using legacy_mode = 'False'
25.02.2026 21:26:14 - 695:plugs.py - jb.plugin.call - StartJingle - DEBUG - Calling: volume.ctrl.set_volume(args=[40], kwargs=None)
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'batt_status'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.plugins.loaded'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.started_at'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.version'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'host.temperature.cpu'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'host.timer.cputemp'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'playerstatus'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'rfid.card_id'
25.02.2026 21:26:26 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'volume.level'
25.02.2026 21:26:26 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '28fa46a0-a604-421f-a596-2f103b820a5b', 'package': 'misc', 'plugin': 'get_app_settings', 'method': None, 'kwargs': {}}
25.02.2026 21:26:26 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: misc.get_app_settings(args=(), kwargs={})
25.02.2026 21:26:26 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'show_covers': True}, 'id': '28fa46a0-a604-421f-a596-2f103b820a5b'}
25.02.2026 21:26:28 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '79e46767-0a97-4713-9c3e-c16982c43b13', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_volume', 'kwargs': {}}
25.02.2026 21:26:28 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_volume(args=(), kwargs={})
25.02.2026 21:26:28 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 40, 'id': '79e46767-0a97-4713-9c3e-c16982c43b13'}
25.02.2026 21:26:28 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'f5a5c779-c589-42f9-b6fb-cd6536b6324d', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_soft_max_volume', 'kwargs': {}}
25.02.2026 21:26:28 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_soft_max_volume(args=(), kwargs={})
25.02.2026 21:26:28 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 70, 'id': 'f5a5c779-c589-42f9-b6fb-cd6536b6324d'}
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'batt_status'
25.02.2026 21:27:23 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '4c4d1ec2-1519-43cc-94ae-d39a59878818', 'package': 'misc', 'plugin': 'get_app_settings', 'method': None, 'kwargs': {}}
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.plugins.loaded'
25.02.2026 21:27:23 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: misc.get_app_settings(args=(), kwargs={})
25.02.2026 21:27:23 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'show_covers': True}, 'id': '4c4d1ec2-1519-43cc-94ae-d39a59878818'}
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.started_at'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'core.version'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'host.temperature.cpu'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'host.timer.cputemp'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'playerstatus'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'rfid.card_id'
25.02.2026 21:27:23 - 260:server.py - jb.pub.server - PubServer - INFO - New subscription for topic b'volume.level'
25.02.2026 21:27:24 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'f95d6d33-33de-4469-9e4e-3c5210608a2a', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_volume', 'kwargs': {}}
25.02.2026 21:27:24 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_volume(args=(), kwargs={})
25.02.2026 21:27:24 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 40, 'id': 'f95d6d33-33de-4469-9e4e-3c5210608a2a'}
25.02.2026 21:27:24 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'e68fbb00-70e5-4bd6-a16d-ad3e936dd230', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_soft_max_volume', 'kwargs': {}}
25.02.2026 21:27:24 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_soft_max_volume(args=(), kwargs={})
25.02.2026 21:27:24 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 70, 'id': 'e68fbb00-70e5-4bd6-a16d-ad3e936dd230'}
25.02.2026 21:27:26 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '1d519760-e22d-4c0e-a568-53ec836a8c9c', 'package': 'cards', 'plugin': 'list_cards', 'method': None, 'kwargs': {}}
25.02.2026 21:27:26 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: cards.list_cards(args=(), kwargs={})
25.02.2026 21:27:26 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {}, 'id': '1d519760-e22d-4c0e-a568-53ec836a8c9c'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '6f1a389f-e377-4e05-86d1-82d0b1ae7651', 'package': 'cards', 'plugin': 'list_cards', 'method': None, 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: cards.list_cards(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {}, 'id': '6f1a389f-e377-4e05-86d1-82d0b1ae7651'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '38668905-90f7-4fe8-8a8c-39f40ddb2964', 'package': 'host', 'plugin': 'get_disk_usage', 'method': None, 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_disk_usage(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'total': 29678, 'used': 3015, 'free': 25426}, 'id': '38668905-90f7-4fe8-8a8c-39f40ddb2964'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '3b192bc1-0a31-4d06-b68e-a758859f6857', 'package': 'host', 'plugin': 'get_ip_address', 'method': None, 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_ip_address(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': '192.168.1.48', 'id': '3b192bc1-0a31-4d06-b68e-a758859f6857'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '20966624-47d3-48ee-a5b4-5c5a3d575eb5', 'package': 'timers', 'plugin': 'timer_fade_volume', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_fade_volume.get_state(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'type': 'VolumeFadoutAndShutdown', 'total_duration': None, 'remaining_seconds': 0, 'progress_percent': 0, 'error': None}, 'id': '20966624-47d3-48ee-a5b4-5c5a3d575eb5'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '27d05e10-ac47-4a1c-8f7e-c84622c7d979', 'package': 'timers', 'plugin': 'timer_shutdown', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_shutdown.get_state(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}, 'id': '27d05e10-ac47-4a1c-8f7e-c84622c7d979'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '1c7b4da0-44d5-401c-927f-76e636747e25', 'package': 'timers', 'plugin': 'timer_stop_player', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_stop_player.get_state(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}, 'id': '1c7b4da0-44d5-401c-927f-76e636747e25'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '0f684b46-f436-44b1-ae93-e29ee8bf97fd', 'package': 'timers', 'plugin': 'timer_idle_shutdown', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_idle_shutdown.get_state(args=(), kwargs={})
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.private_timer_idle_check.get_state(args=(), kwargs=None)
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.private_timer_idle_shutdown.get_state(args=(), kwargs=None)
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'running': False, 'remaining_seconds': 0, 'wait_seconds': 0}, 'id': '0f684b46-f436-44b1-ae93-e29ee8bf97fd'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'f3cc69fc-6872-409b-a1cd-b9e5f09b7cb5', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_soft_max_volume', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_soft_max_volume(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 70, 'id': 'f3cc69fc-6872-409b-a1cd-b9e5f09b7cb5'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '3d70ab41-e887-45d3-8b1a-37dad8625451', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_outputs', 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_outputs(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'active_alias': 'Built-in speakers', 'active_sink': 'alsa_output.platform-bcm2835_audio.analog-stereo', 'sink_list': [{'alias': 'Built-in speakers', 'pulse_sink_name': 'alsa_output.platform-bcm2835_audio.analog-stereo', 'volume_limit': 100}]}, 'id': '3d70ab41-e887-45d3-8b1a-37dad8625451'}
26.02.2026 01:41:05 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '733ac103-9635-47b9-a2e3-e99263e50f84', 'package': 'host', 'plugin': 'get_autohotspot_status', 'method': None, 'kwargs': {}}
26.02.2026 01:41:05 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_autohotspot_status(args=(), kwargs={})
26.02.2026 01:41:05 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 'not-installed', 'id': '733ac103-9635-47b9-a2e3-e99263e50f84'}
26.02.2026 01:41:20 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '7da23e14-be42-41ea-b6da-641badc3e4fe', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_volume', 'kwargs': {}}
26.02.2026 01:41:20 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_volume(args=(), kwargs={})
26.02.2026 01:41:20 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 40, 'id': '7da23e14-be42-41ea-b6da-641badc3e4fe'}
26.02.2026 01:41:20 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '34e47259-cf23-4c2f-9a08-c7bc739e3fac', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_soft_max_volume', 'kwargs': {}}
26.02.2026 01:41:20 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_soft_max_volume(args=(), kwargs={})
26.02.2026 01:41:20 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 70, 'id': '34e47259-cf23-4c2f-9a08-c7bc739e3fac'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'a4586a5a-90a2-4b35-bf71-e38d6f08d91e', 'package': 'host', 'plugin': 'get_disk_usage', 'method': None, 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_disk_usage(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'total': 29678, 'used': 3015, 'free': 25426}, 'id': 'a4586a5a-90a2-4b35-bf71-e38d6f08d91e'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'b86472a4-a96a-4f9d-9174-4aea39cdfefa', 'package': 'host', 'plugin': 'get_ip_address', 'method': None, 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_ip_address(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': '192.168.1.48', 'id': 'b86472a4-a96a-4f9d-9174-4aea39cdfefa'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '70b12e36-5b40-49ce-be98-f50f9366d5aa', 'package': 'timers', 'plugin': 'timer_fade_volume', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_fade_volume.get_state(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'type': 'VolumeFadoutAndShutdown', 'total_duration': None, 'remaining_seconds': 0, 'progress_percent': 0, 'error': None}, 'id': '70b12e36-5b40-49ce-be98-f50f9366d5aa'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '2c8a072a-0748-4d76-9765-d5d666217033', 'package': 'timers', 'plugin': 'timer_shutdown', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_shutdown.get_state(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}, 'id': '2c8a072a-0748-4d76-9765-d5d666217033'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'afb04b00-f4f0-41b8-acf7-483bb89668b5', 'package': 'timers', 'plugin': 'timer_stop_player', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_stop_player.get_state(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'remaining_seconds': 0, 'wait_seconds': 3600, 'type': 'GenericTimerClass'}, 'id': 'afb04b00-f4f0-41b8-acf7-483bb89668b5'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': 'a2283679-a05e-4b3f-b199-e3ee3e3eef08', 'package': 'timers', 'plugin': 'timer_idle_shutdown', 'method': 'get_state', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.timer_idle_shutdown.get_state(args=(), kwargs={})
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.private_timer_idle_check.get_state(args=(), kwargs=None)
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: timers.private_timer_idle_shutdown.get_state(args=(), kwargs=None)
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'enabled': False, 'running': False, 'remaining_seconds': 0, 'wait_seconds': 0}, 'id': 'a2283679-a05e-4b3f-b199-e3ee3e3eef08'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '41e11f15-d9d6-482d-8952-581e295ceae9', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_outputs', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_outputs(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': {'active_alias': 'Built-in speakers', 'active_sink': 'alsa_output.platform-bcm2835_audio.analog-stereo', 'sink_list': [{'alias': 'Built-in speakers', 'pulse_sink_name': 'alsa_output.platform-bcm2835_audio.analog-stereo', 'volume_limit': 100}]}, 'id': '41e11f15-d9d6-482d-8952-581e295ceae9'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '3a9ab6a4-d65a-467a-a279-618a398e5e49', 'package': 'volume', 'plugin': 'ctrl', 'method': 'get_soft_max_volume', 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: volume.ctrl.get_soft_max_volume(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 70, 'id': '3a9ab6a4-d65a-467a-a279-618a398e5e49'}
26.02.2026 01:41:22 - 111:server.py - jb.rpc.server - MainThread - DEBUG - Request: {'id': '42dbe590-679f-4f03-ac24-ff8965daeeac', 'package': 'host', 'plugin': 'get_autohotspot_status', 'method': None, 'kwargs': {}}
26.02.2026 01:41:22 - 695:plugs.py - jb.plugin.call - MainThread - DEBUG - Calling: host.get_autohotspot_status(args=(), kwargs={})
26.02.2026 01:41:22 - 151:server.py - jb.rpc.server - MainThread - DEBUG - Sending response: {'result': 'not-installed', 'id': '42dbe590-679f-4f03-ac24-ff8965daeeac'}

[AlvinSchiller]

Hi, did you connect all 8 pins (incl. IRQ) like described here?

[taylorcjohnston]

yes all 8 pins are connected and correct ports. defaults. and in installation i chose all the defaults as well . i just pulled out the multimeter again and they all are positive for continuity . my grnd is different than the image but that does not matter.

[taylorcjohnston]

re-installed with bookworm pre-release and it now works. after close to 20+ installs of bullseye