activate_scenario.sh
Fri Apr 18 09:21:02 CEST 2025
scenario: vorraumOn
phoneName: 0
dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as '
-> execution of scenario
scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'
(/srv/www/viehai/shelly/log_scenarios/2025/04/18/_0.log)
vorher: 09:21:02.724
http://192.168.12.103/relay/0?turn=on |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"overpower": false,
"source": "input"
|
nachher: 09:21:02.756
Dauer: 32 msec
phName: 'phoneName:'0''
scenario: 'Vorzimmer EIN' - 'vorraumOn' - 'vorraumon'
urls : 1
vorher : 09:21:02.697
nachher: 09:21:02.770
Dauer : 73 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/04/18//scene_vorraumOn_20250418_092102.txt'
syslog
Apr 18 09:18:00 srv4hai mqtt_process[4486]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 09:18:00 srv4hai mqtt_process[4522]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:18:00 srv4hai mqtt_process[4547]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:18:01 srv4hai CRON[4573]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 09:18:22 srv4hai ping_SP[14685]: SHELLY (0)- ping 192.168.12.61 4 not answered (1) @~1744960702 - 'ping_SP_0'
Apr 18 09:18:34 srv4hai mqtt_process[4601]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:18:34 srv4hai mqtt_process[4626]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:18:48 srv4hai ping_SP[14685]: SHELLY (0)- ping 192.168.12.61 5 not answered (1) @~1744960728 - 'ping_SP_0'
Apr 18 09:18:48 srv4hai ping_SP[14685]: SHELLY (0)- ping 192.168.12.61 5 not answered - abort (14685) @~1744960728 - rm 'ping_SP_0'
Apr 18 09:18:49 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 18 09:18:49 srv4hai check_presence[4669]: SHELLY (0) found /srv/www/viehai/shelly/meter/ping_SP_0.DEAD and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_0.No
Apr 18 09:18:49 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 18 09:18:49 srv4hai check_presence[4669]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.No
Apr 18 09:18:49 srv4hai check_presence[4669]: SHELLY (ping_SP__NO_ping)-(0) what: 'presence' - persPresent: '(__/__) (__/__)' - anyPresent: 'false' - otherPresent:'false'
Apr 18 09:18:49 srv4hai postfix/pickup[3838]: 15FAB63463: uid=33 from=
Apr 18 09:18:49 srv4hai postfix/cleanup[4727]: 15FAB63463: message-id=<20250418071849.15FAB63463@mail.viehai.at>
Apr 18 09:18:49 srv4hai postfix/qmgr[8566]: 15FAB63463: from=, size=8362, nrcpt=1 (queue active)
Apr 18 09:18:49 srv4hai check_presence[4669]: SHELLY - check 'presence': anyPresent(false) - lScene(scene_offAll) - sceneOFF(offAll)
Apr 18 09:18:49 srv4hai check_presence[4669]: SHELLY (ping_SP__NO_ping)-(0)-presence: activate_scenario.shtml: offAll
Apr 18 09:18:49 srv4hai activate_scenario[4760]: SHELLY scenario: 'offAll(offall)' started
Apr 18 09:18:49 srv4hai activate_scenario[4760]: SHELLY dayState='day' <--> DayTime='unchecked' NightTime='checked' flag 'execute* set as ' - phoneName:'kanaDaham'
Apr 18 09:18:49 srv4hai activate_scenario[4760]: SHELLY scenario: 'offAll(offall)' NOT EXECUTED, phoneName:'kanaDaham'
Apr 18 09:18:49 srv4hai ping_SP[14685]: SHELLY (0)- TERMINATING now
Apr 18 09:18:50 srv4hai postfix/relay/smtp[4734]: 15FAB63463: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.99, delays=0.03/0.02/0.66/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as D6F9D60190)
Apr 18 09:18:50 srv4hai postfix/qmgr[8566]: 15FAB63463: removed
Apr 18 09:18:56 srv4hai named[30406]: connection refused resolving '116.140.65.176.in-addr.arpa/PTR/IN': 164.92.208.132#53
Apr 18 09:18:59 srv4hai mqtt_process[4812]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:18:59 srv4hai mqtt_process[4837]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:19:00 srv4hai mqtt_process[4862]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 09:19:01 srv4hai CRON[4888]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 09:19:09 srv4hai named[30406]: connection refused resolving '116.140.65.176.in-addr.arpa/PTR/IN': 164.92.208.132#53
Apr 18 09:19:12 srv4hai postfix/smtpd[4810]: connect from unknown[176.65.140.116]
Apr 18 09:19:12 srv4hai postfix/smtpd[4810]: lost connection after CONNECT from unknown[176.65.140.116]
Apr 18 09:19:12 srv4hai postfix/smtpd[4810]: disconnect from unknown[176.65.140.116] commands=0/0
Apr 18 09:19:34 srv4hai mqtt_process[4897]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:19:34 srv4hai mqtt_process[4922]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:00 srv4hai mqtt_process[4948]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 09:20:00 srv4hai mqtt_process[4973]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:00 srv4hai mqtt_process[4998]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:01 srv4hai CRON[5025]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 09:20:01 srv4hai CRON[5026]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 18 09:20:01 srv4hai meter.sh: SHELLY meter.sh started
Apr 18 09:20:02 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 18 09:20:02 srv4hai check_shelly.sh: SHELLY last scenery executed: 'offAll'
Apr 18 09:20:02 srv4hai check_shelly.sh: SHELLY found '1' active shelly outputs
Apr 18 09:20:02 srv4hai meter.sh: SHELLY meter.sh ended
Apr 18 09:20:02 srv4hai postfix/pickup[3838]: 1DC6763463: uid=33 from=
Apr 18 09:20:02 srv4hai postfix/cleanup[4727]: 1DC6763463: message-id=<20250418072002.1DC6763463@mail.viehai.at>
Apr 18 09:20:02 srv4hai postfix/qmgr[8566]: 1DC6763463: from=, size=663, nrcpt=1 (queue active)
Apr 18 09:20:02 srv4hai postfix/local[5152]: 1DC6763463: 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 09:20:02 srv4hai postfix/qmgr[8566]: 1DC6763463: removed
Apr 18 09:20:19 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5159, secured, session=<+k3PWQgz/JgAAAAAAAAAAAAAAAAAAAAB>
Apr 18 09:20:19 srv4hai dovecot: imap(miglincit)<5159><+k3PWQgz/JgAAAAAAAAAAAAAAAAAAAAB>: 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 18 09:20:34 srv4hai mqtt_process[5160]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:34 srv4hai mqtt_process[5185]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:39 srv4hai kernel: [90938952.818811] CPU7: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818812] CPU5: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818814] CPU4: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818815] CPU0: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818817] CPU2: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818817] CPU6: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818819] CPU1: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:39 srv4hai kernel: [90938952.818820] CPU3: Package temperature above threshold, cpu clock throttled (total events = 812281615)
Apr 18 09:20:52 srv4hai dhcpd[30231]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 18 09:20:52 srv4hai dhcpd[30231]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 18 09:20:52 srv4hai dhcpd[30231]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 18 09:20:52 srv4hai dhcpd[30231]: execute_statement argv[1] = 192.168.12.61
Apr 18 09:20:52 srv4hai dhcpd[30231]: execute_statement argv[2] = fpThomas
Apr 18 09:20:52 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 18 09:20:52 srv4hai dhcpd[30231]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 18 09:20:52 srv4hai dhcpd[30231]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93480ae320 192.168.12.252#58845/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#57129/key rndc-key: signer "rndc-key" approved
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#57129/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#57129/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#57129/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#57129/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr 18 09:20:52 srv4hai dhcpd[30231]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93300d0220 192.168.12.252#42687/key rndc-key: signer "rndc-key" approved
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93300d0220 192.168.12.252#42687/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr 18 09:20:52 srv4hai named[30406]: client @0x7f93300d0220 192.168.12.252#42687/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 18 09:20:52 srv4hai dhcpd[30231]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr 18 09:20:52 srv4hai ping_SP[5227]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 18 09:20:52 srv4hai named[30406]: resolver priming query complete
Apr 18 09:20:59 srv4hai mqtt_process[5263]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:20:59 srv4hai mqtt_process[5288]: SHELLY - shellyType: 'shelly-2PM' not handled for 'shelly-45'/'45-TBD'!!
Apr 18 09:21:00 srv4hai mqtt_process[5313]: SHELLY - shellyType: 'shelly-PM-mini' not handled for 'shelly-49'/'49-TBD'!!
Apr 18 09:21:01 srv4hai CRON[5339]: (root) CMD (/etc/linetest/linetest.sh)
Apr 18 09:21:02 srv4hai common.sh: SHELLY: BTstatus[0]:'NO_BTdweet' dweetAge:''=-
Apr 18 09:21:02 srv4hai check_presence[5352]: 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 09:21:02 srv4hai check_presence[5352]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly/meter/ping_SP_0.LIVE' for what: 'coming'
Apr 18 09:21:02 srv4hai common.sh: SHELLY: BTstatus[1]:'NO_BTdweet' dweetAge:''=-
Apr 18 09:21:02 srv4hai bt_scan[5249]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 18 09:21:02 srv4hai bt_scan[5249]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Apr 18 09:21:02 srv4hai check_presence[5352]: SHELLY (1) found /srv/www/viehai/shelly/meter/ping_SP_1.DEAD and NO_BTdweet//srv/www/viehai/shelly/meter/BTscan_BTid_1.No
Apr 18 09:21:02 srv4hai check_presence[5352]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Apr 18 09:21:02 srv4hai check_presence[5352]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Apr 18 09:21:02 srv4hai check_presence[5352]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 18 09:21:02 srv4hai activate_scenario[5459]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 18 09:21:02 srv4hai activate_scenario[5459]: SHELLY dayState='day' <--> DayTime='checked' NightTime='checked' flag 'execute* set as ' - phoneName:'0'
finished