activate_scenario.sh
Sat Apr 26 14:51:47 CEST 2025
scenario: vorraumOn
phoneName: 0

scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'

(/srv/www/viehai/shelly/log_scenarios/2025/04/26/_0.log)

vorher: 14:51:47.219

  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "input"

nachher: 14:51:47.251
Dauer: 32 msec phName: 'phoneName:'0''

scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'

urls : 1
vorher : 14:51:47.199
nachher: 14:51:47.263
Dauer : 65 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/26//scene_vorraumOn_20250426_145147.txt'

syslog

Apr 26 14:48:00 srv4hai mqtt_process[4392]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 26 14:48:00 srv4hai mqtt_process[4428]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:48:00 srv4hai mqtt_process[4453]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:48:01 srv4hai CRON[4490]: (root) CMD (/etc/linetest/linetest.sh)
Apr 26 14:48:17 srv4hai mqtt_process[4497]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:48:17 srv4hai mqtt_process[4533]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:48:24 srv4hai dhcpd[30231]: DHCPDISCOVER from 98:cd:ac:2d:5a:11 via enp2s0
Apr 26 14:48:24 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.119 to 98:cd:ac:2d:5a:11 via enp2s0
Apr 26 14:48:24 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Apr 26 14:48:24 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.119
Apr 26 14:48:24 srv4hai dhcpd[30231]: execute_statement argv[2] = shelly-19
Apr 26 14:48:24 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.119 (192.168.12.252) from 98:cd:ac:2d:5a:11 via enp2s0
Apr 26 14:48:24 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.119 to 98:cd:ac:2d:5a:11 via enp2s0
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f93506e3340 192.168.12.252#46885/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-19.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#36857/key rndc-key: signer "rndc-key" approved
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#36857/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-19.viehai.at' TXT
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#36857/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-19.viehai.at' TXT "0058d808d26aa46bf6275641574f8413b8"
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#36857/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-19.viehai.at' A
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#36857/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-19.viehai.at' A 192.168.12.119
Apr 26 14:48:24 srv4hai dhcpd[30231]: Added new forward map from shelly-19.viehai.at to 192.168.12.119
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#41857/key rndc-key: signer "rndc-key" approved
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#41857/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '119.12.168.192.in-addr.arpa' PTR
Apr 26 14:48:24 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#41857/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '119.12.168.192.in-addr.arpa' PTR shelly-19.viehai.at.
Apr 26 14:48:24 srv4hai dhcpd[30231]: Added reverse map from 119.12.168.192.in-addr.arpa. to shelly-19.viehai.at
Apr 26 14:48:24 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-19' - shIP/2:'' perform:'conf'
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-19' - shIP/2:'192.168.12.119'
Apr 26 14:48:25 srv4hai mosquitto[29419]: New connection from 192.168.12.119 on port 1883.
Apr 26 14:48:25 srv4hai mosquitto[29419]: New client connected from 192.168.12.119 as shelly1-98CDAC2D5A11 (c1, k60, u'shmqtt').
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY: usage/name 'GB Fernsehlampe' already set for '192.168.12.119'
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(0)' - allready transmitted - skipping
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(1)' - allready transmitted - skipping
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(2)' - allready transmitted - skipping
Apr 26 14:48:25 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(3)' - allready transmitted - skipping
Apr 26 14:48:27 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=4658, secured, session=
Apr 26 14:48:27 srv4hai dovecot: imap(miglincit)<4658>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 26 14:48:45 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745671725 - 'ping_SP_1'
Apr 26 14:48:45 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 26 14:48:59 srv4hai mqtt_process[4681]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:48:59 srv4hai mqtt_process[4706]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:49:00 srv4hai mqtt_process[4731]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 26 14:49:01 srv4hai CRON[4757]: (root) CMD (/etc/linetest/linetest.sh)
Apr 26 14:49:17 srv4hai mqtt_process[4762]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:49:17 srv4hai mqtt_process[4787]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:49:39 srv4hai dhcpd[30231]: DHCPDISCOVER from f6:aa:b4:cc:9a:8d via enp2s0
Apr 26 14:49:40 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.48 to f6:aa:b4:cc:9a:8d via enp2s0
Apr 26 14:49:41 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.48 (192.168.12.252) from f6:aa:b4:cc:9a:8d via enp2s0
Apr 26 14:49:41 srv4hai dhcpd[30231]: Wrote 0 deleted host decls to leases file.
Apr 26 14:49:41 srv4hai dhcpd[30231]: Wrote 0 new dynamic host decls to leases file.
Apr 26 14:49:41 srv4hai dhcpd[30231]: Wrote 30 leases to leases file.
Apr 26 14:49:41 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.48 to f6:aa:b4:cc:9a:8d via enp2s0
Apr 26 14:49:42 srv4hai named[30406]: resolver priming query complete
Apr 26 14:49:42 srv4hai named[30406]: no valid RRSIG resolving 'resolver.arpa/DS/IN': 8.8.8.8#53
Apr 26 14:49:43 srv4hai named[30406]: no valid RRSIG resolving '_dns.resolver.arpa/DS/IN': 8.8.8.8#53
Apr 26 14:49:43 srv4hai named[30406]: insecurity proof failed resolving '_dns.resolver.arpa/TYPE64/IN': 8.8.8.8#53
Apr 26 14:49:43 srv4hai named[30406]: resolver priming query complete
Apr 26 14:49:44 srv4hai named[30406]: resolver priming query complete
Apr 26 14:49:45 srv4hai named[30406]: resolver priming query complete
Apr 26 14:49:48 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745671788 - 'ping_SP_1'
Apr 26 14:49:48 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 26 14:49:56 srv4hai named[30406]: resolver priming query complete
Apr 26 14:50:00 srv4hai mqtt_process[4839]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:50:00 srv4hai mqtt_process[4864]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:50:00 srv4hai mqtt_process[4889]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 26 14:50:01 srv4hai CRON[4916]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 26 14:50:01 srv4hai CRON[4917]: (root) CMD (/etc/linetest/linetest.sh)
Apr 26 14:50:01 srv4hai meter.sh: SHELLY meter.sh started
Apr 26 14:50:01 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 26 14:50:01 srv4hai check_shelly.sh: SHELLY last scenery executed: 'vorraumOn'
Apr 26 14:50:01 srv4hai check_shelly.sh: SHELLY found '8' active shelly outputs
Apr 26 14:50:01 srv4hai meter.sh: SHELLY meter.sh ended
Apr 26 14:50:01 srv4hai postfix/pickup[26280]: D864B63465: uid=33 from=
Apr 26 14:50:01 srv4hai postfix/cleanup[5043]: D864B63465: message-id=<20250426125001.D864B63465@mail.viehai.at>
Apr 26 14:50:01 srv4hai postfix/qmgr[8566]: D864B63465: from=, size=663, nrcpt=1 (queue active)
Apr 26 14:50:01 srv4hai postfix/local[5045]: D864B63465: 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 26 14:50:01 srv4hai postfix/qmgr[8566]: D864B63465: removed
Apr 26 14:50:17 srv4hai mqtt_process[5048]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:50:17 srv4hai mqtt_process[5073]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:50:51 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1745671851 - 'ping_SP_1'
Apr 26 14:50:51 srv4hai ping_SP[21575]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Apr 26 14:51:00 srv4hai mqtt_process[5127]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 26 14:51:00 srv4hai mqtt_process[5152]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:51:00 srv4hai mqtt_process[5177]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:51:00 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 26 14:51:01 srv4hai CRON[5203]: (root) CMD (/etc/linetest/linetest.sh)
Apr 26 14:51:01 srv4hai fetchmail[1651]: 68513 messages (68513 seen) for tmiglinci at mail.akis.at (1353541060 octets).
Apr 26 14:51:02 srv4hai fetchmail[1651]: 11879 messages (11879 seen) for srozum at mail.akis.at (1398074789 octets).
Apr 26 14:51:02 srv4hai fetchmail[1651]: 30572 messages (30572 seen) for mrozum at mail.akis.at (973608385 octets).
Apr 26 14:51:03 srv4hai fetchmail[1651]: 1169 messages (1169 seen) for admin@bastelstube.wien at pop3.world4you.com (52284237 octets).
Apr 26 14:51:17 srv4hai mqtt_process[5210]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:51:17 srv4hai mqtt_process[5235]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 26 14:51:26 srv4hai mosquitto[29419]: Client shelly1-98CDAC2D5A11 has exceeded timeout, disconnecting.
Apr 26 14:51:26 srv4hai mosquitto[29419]: Socket error on client shelly1-98CDAC2D5A11, disconnecting.
Apr 26 14:51:27 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5263, secured, session=
Apr 26 14:51:27 srv4hai dovecot: imap(miglincit)<5263>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 26 14:51:30 srv4hai kernel: [91650011.354551] CPU4: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354552] CPU5: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354554] CPU2: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354555] CPU7: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354557] CPU6: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354558] CPU3: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354559] CPU1: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:30 srv4hai kernel: [91650011.354560] CPU0: Package temperature above threshold, cpu clock throttled (total events = 892551965)
Apr 26 14:51:36 srv4hai dhcpd[30231]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 26 14:51:36 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 26 14:51:36 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 26 14:51:36 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 26 14:51:36 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 26 14:51:36 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 26 14:51:36 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 26 14:51:36 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f932c079890 192.168.12.252#47541/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#55239/key rndc-key: signer "rndc-key" approved
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#55239/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#55239/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#55239/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#55239/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr 26 14:51:36 srv4hai dhcpd[30231]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#38439/key rndc-key: signer "rndc-key" approved
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#38439/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr 26 14:51:36 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#38439/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Apr 26 14:51:36 srv4hai dhcpd[30231]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr 26 14:51:36 srv4hai ping_SP[5283]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 26 14:51:37 srv4hai named[30406]: resolver priming query complete
Apr 26 14:51:38 srv4hai named[30406]: resolver priming query complete
Apr 26 14:51:41 srv4hai named[30406]: resolver priming query complete
Apr 26 14:51:42 srv4hai named[30406]: resolver priming query complete
Apr 26 14:51:46 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 26 14:51:46 srv4hai check_presence[5314]: 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 26 14:51:46 srv4hai check_presence[5314]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'coming'
Apr 26 14:51:46 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 26 14:51:46 srv4hai check_presence[5314]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.LIVE and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.Yes
Apr 26 14:51:46 srv4hai check_presence[5314]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_1.LIVE' for what: 'coming'
Apr 26 14:51:46 srv4hai check_presence[5314]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Apr 26 14:51:47 srv4hai check_presence[5314]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Apr 26 14:51:47 srv4hai check_presence[5314]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 26 14:51:47 srv4hai activate_scenario[5399]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 26 14:51:47 srv4hai activate_scenario[5399]: SHELLY dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'0'

finished