activate_scenario.sh
Fri Apr 18 17:33:53 CEST 2025
scenario: library_UM
source: 192.168.12.124

scenario: 'Licht Bücherregal UM-schalten' - 'library_UM' - 'library_um'

WZ Licht Bücherregal (/srv/www/viehai/shelly/log_scenarios/2025/04/18/_0.log)

vorher: 17:33:53.510

  "ison": false,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 17:33:53.576
Dauer: 67 msec phName: 'shelly-24:'Empore Licht''

scenario: 'Licht Bücherregal UM-schalten' - 'library_UM' - 'library_um'

urls : 1
vorher : 17:33:53.460
nachher: 17:33:53.589
Dauer : 129 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/18//scene_library_UM_20250418_173353.txt'

syslog

Apr 18 17:30:00 srv4hai mqtt_process[15824]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 17:30:01 srv4hai CRON[15852]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Apr 18 17:30:01 srv4hai CRON[15853]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 18 17:30:01 srv4hai CRON[15854]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 17:30:01 srv4hai meter.sh: SHELLY meter.sh started
Apr 18 17:30:01 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 18 17:30:01 srv4hai check_shelly.sh: SHELLY last scenery executed: 'pcOFF'
Apr 18 17:30:01 srv4hai check_shelly.sh: SHELLY found '5' active shelly outputs
Apr 18 17:30:01 srv4hai meter.sh: SHELLY meter.sh ended
Apr 18 17:30:01 srv4hai postfix/pickup[1198]: CF88E63463: uid=33 from=
Apr 18 17:30:01 srv4hai postfix/cleanup[16010]: CF88E63463: message-id=<20250418153001.CF88E63463@mail.viehai.at>
Apr 18 17:30:01 srv4hai postfix/qmgr[8566]: CF88E63463: from=, size=663, nrcpt=1 (queue active)
Apr 18 17:30:01 srv4hai postfix/local[16012]: CF88E63463: to=, orig_to=, relay=local, delay=0.04, delays=0.03/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 18 17:30:01 srv4hai postfix/qmgr[8566]: CF88E63463: removed
Apr 18 17:30:08 srv4hai bt_scan[15761]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 18 17:30:08 srv4hai bt_scan[15761]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Apr 18 17:30:25 srv4hai dhcpd[30231]: DHCPRELEASE of 192.168.12.2 from 24:5a:4c:1c:1e:f4 via enp2s0 (not found)
Apr 18 17:30:25 srv4hai dhcpd[30231]: DHCPDISCOVER from 24:5a:4c:1c:1e:f4 via enp2s0
Apr 18 17:30:25 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.2 to 24:5a:4c:1c:1e:f4 via enp2s0
Apr 18 17:30:25 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Apr 18 17:30:25 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.2
Apr 18 17:30:25 srv4hai dhcpd[30231]: execute_statement argv[2] = ap2hai
Apr 18 17:30:25 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.2 (192.168.12.252) from 24:5a:4c:1c:1e:f4 via enp2s0
Apr 18 17:30:25 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.2 to 24:5a:4c:1c:1e:f4 via enp2s0
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f9348022510 192.168.12.252#38787/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: ap2hai.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 18 17:30:25 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.2'(2) device 'ap2hai'
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#38065/key rndc-key: signer "rndc-key" approved
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#38065/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'ap2hai.viehai.at' TXT
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#38065/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'ap2hai.viehai.at' TXT "31a0d441667de220424b7789941807db7d"
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#38065/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'ap2hai.viehai.at' A
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#38065/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'ap2hai.viehai.at' A 192.168.12.2
Apr 18 17:30:25 srv4hai dhcpd[30231]: Added new forward map from ap2hai.viehai.at to 192.168.12.2
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f932c05b620 192.168.12.252#52761/key rndc-key: signer "rndc-key" approved
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f932c05b620 192.168.12.252#52761/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '2.12.168.192.in-addr.arpa' PTR
Apr 18 17:30:25 srv4hai named[30406]: client @0x7f932c05b620 192.168.12.252#52761/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '2.12.168.192.in-addr.arpa' PTR ap2hai.viehai.at.
Apr 18 17:30:25 srv4hai dhcpcommit.sh: SHELLY device 'ap2hai' known and accepted
Apr 18 17:30:25 srv4hai dhcpd[30231]: Added reverse map from 2.12.168.192.in-addr.arpa. to ap2hai.viehai.at
Apr 18 17:30:29 srv4hai dhcpd[30231]: DHCPRELEASE of 192.168.12.3 from 70:a7:41:9a:7d:4e via enp2s0 (not found)
Apr 18 17:30:29 srv4hai dhcpd[30231]: DHCPDISCOVER from 70:a7:41:9a:7d:4e via enp2s0
Apr 18 17:30:29 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.3 to 70:a7:41:9a:7d:4e via enp2s0
Apr 18 17:30:29 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Apr 18 17:30:29 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.3
Apr 18 17:30:29 srv4hai dhcpd[30231]: execute_statement argv[2] = ap3hai
Apr 18 17:30:29 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.3 (192.168.12.252) from 70:a7:41:9a:7d:4e via enp2s0
Apr 18 17:30:29 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.3 to 70:a7:41:9a:7d:4e via enp2s0
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f9348048e40 192.168.12.252#44793/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: ap3hai.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 18 17:30:29 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.3'(3) device 'ap3hai'
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36969/key rndc-key: signer "rndc-key" approved
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36969/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'ap3hai.viehai.at' TXT
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36969/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'ap3hai.viehai.at' TXT "31763f1a8a980dd918aaf8fff3416a5028"
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36969/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'ap3hai.viehai.at' A
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36969/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'ap3hai.viehai.at' A 192.168.12.3
Apr 18 17:30:29 srv4hai dhcpd[30231]: Added new forward map from ap3hai.viehai.at to 192.168.12.3
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f931006ae50 192.168.12.252#47067/key rndc-key: signer "rndc-key" approved
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f931006ae50 192.168.12.252#47067/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '3.12.168.192.in-addr.arpa' PTR
Apr 18 17:30:29 srv4hai named[30406]: client @0x7f931006ae50 192.168.12.252#47067/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '3.12.168.192.in-addr.arpa' PTR ap3hai.viehai.at.
Apr 18 17:30:29 srv4hai dhcpd[30231]: Added reverse map from 3.12.168.192.in-addr.arpa. to ap3hai.viehai.at
Apr 18 17:30:29 srv4hai dhcpcommit.sh: SHELLY device 'ap3hai' known and accepted
Apr 18 17:30:33 srv4hai mqtt_process[16052]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:30:33 srv4hai mqtt_process[16077]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:30:41 srv4hai kernel: [90968355.292605] CPU5: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292607] CPU4: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292608] CPU0: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292609] CPU7: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292611] CPU2: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292612] CPU6: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292614] CPU3: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:41 srv4hai kernel: [90968355.292615] CPU1: Package temperature above threshold, cpu clock throttled (total events = 815029348)
Apr 18 17:30:42 srv4hai kernel: [90968355.304606] CPU7: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304607] CPU5: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304609] CPU0: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304609] CPU4: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304610] CPU6: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304611] CPU2: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304612] CPU1: Package temperature/speed normal
Apr 18 17:30:42 srv4hai kernel: [90968355.304613] CPU3: Package temperature/speed normal
Apr 18 17:30:59 srv4hai mqtt_process[16111]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:30:59 srv4hai mqtt_process[16136]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:31:00 srv4hai mqtt_process[16161]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 17:31:01 srv4hai CRON[16187]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 17:31:01 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1744990261 - 'ping_SP_0'
Apr 18 17:31:01 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 18 17:31:33 srv4hai mqtt_process[16224]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:31:33 srv4hai mqtt_process[16250]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:31:37 srv4hai bt_scan[16210]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Apr 18 17:31:37 srv4hai bt_scan[16210]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Apr 18 17:31:37 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 18 17:31:37 srv4hai check_presence[16293]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.LIVE and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.No
Apr 18 17:31:37 srv4hai check_presence[16293]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'presence'
Apr 18 17:31:37 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 18 17:31:37 srv4hai check_presence[16293]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 18 17:31:37 srv4hai check_presence[16293]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Apr 18 17:31:38 srv4hai check_presence[16293]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_pcOFF) - sceneOFF(offAll)
Apr 18 17:31:38 srv4hai check_presence[16293]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Apr 18 17:31:57 srv4hai mosquitto[29419]: Saving in-memory database to /var/lib/mosquitto/mosquitto.db.
Apr 18 17:31:59 srv4hai mqtt_process[16388]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:31:59 srv4hai mqtt_process[16413]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:32:00 srv4hai mqtt_process[16439]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 17:32:01 srv4hai CRON[16465]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 17:32:06 srv4hai kernel: [90968439.838627] CPU5: Core temperature/speed normal
Apr 18 17:32:06 srv4hai kernel: [90968439.838628] CPU1: Core temperature/speed normal
Apr 18 17:32:14 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1744990334 - 'ping_SP_0'
Apr 18 17:32:14 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 18 17:32:19 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16495, secured, session=
Apr 18 17:32:19 srv4hai dovecot: imap(miglincit)<16495>: Logged out in=137 out=1087 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 18 17:32:33 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 18 17:32:33 srv4hai mqtt_process[16499]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:32:33 srv4hai mqtt_process[16524]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:32:34 srv4hai fetchmail[1651]: 68062 messages (68062 seen) for tmiglinci at mail.akis.at (1305894009 octets).
Apr 18 17:32:35 srv4hai fetchmail[1651]: 11843 messages (11843 seen) for srozum at mail.akis.at (1395619468 octets).
Apr 18 17:32:35 srv4hai fetchmail[1651]: 30403 messages (30403 seen) for mrozum at mail.akis.at (954271304 octets).
Apr 18 17:32:36 srv4hai fetchmail[1651]: 1169 messages (1169 seen) for admin@bastelstube.wien at pop3.world4you.com (52284237 octets).
Apr 18 17:32:50 srv4hai activate_scenario[16551]: SHELLY scenario: 'library_UM(library_um)' started
Apr 18 17:32:50 srv4hai activate_scenario[16551]: SHELLY dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - shelly-24:'Empore Licht'
Apr 18 17:32:50 srv4hai activate_scenario[16551]: SHELLY scenario: 'library_UM(library_um)' TO BE EXECUTED, shelly-24:'Empore Licht'
Apr 18 17:32:50 srv4hai activate_scenario[16551]: SHELLY: scenario: 'library_UM(library_um)'-'shelly-10'-'WZ Licht Bücherregal' --> '0?turn=toggle'
Apr 18 17:32:50 srv4hai proc_url[16611]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/04/18/_0.log' - shellyUsage:'WZ Licht Bücherregal' - scUrl:'http://192.168.12.110/relay/0?turn=toggle' - phName:'shelly-24:'Empore Licht''
Apr 18 17:32:50 srv4hai activate_scenario[16551]: SHELLY scenario: 'library_UM(library_um)' took 94 msec, shelly-24:'Empore Licht'
Apr 18 17:33:00 srv4hai mqtt_process[16637]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:33:00 srv4hai mqtt_process[16662]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 17:33:00 srv4hai mqtt_process[16701]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:33:01 srv4hai CRON[16730]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 17:33:25 srv4hai bt_scan[16739]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 18 17:33:25 srv4hai bt_scan[16739]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Apr 18 17:33:27 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1744990407 - 'ping_SP_0'
Apr 18 17:33:27 srv4hai ping_SP[27808]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 18 17:33:33 srv4hai mqtt_process[16796]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:33:33 srv4hai mqtt_process[16821]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 17:33:35 srv4hai named[30406]: connection refused resolving '116.140.65.176.in-addr.arpa/PTR/IN': 164.92.208.132#53
Apr 18 17:33:45 srv4hai named[30406]: connection refused resolving '116.140.65.176.in-addr.arpa/PTR/IN': 164.92.208.132#53
Apr 18 17:33:53 srv4hai activate_scenario[16849]: SHELLY scenario: 'library_UM(library_um)' started
Apr 18 17:33:53 srv4hai activate_scenario[16849]: SHELLY dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - shelly-24:'Empore Licht'

finished