activate_scenario.sh
Tue Dec 24 22:33:54 CET 2024
scenario: vorraumOn
phoneName: 0

'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'

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

VZ Licht (/srv/www/viehai/shelly/log_scenarios/2024/12/24/_0.log)

vorher: 22:33:54.275

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": "http"

nachher: 22:33:54.337
Dauer: 62 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 22:33:54.235
nachher: 22:33:54.344
Dauer : 111 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/24//scene_vorraumOn_20241224_223354.txt'


syslog

Dec 24 22:30:01 srv4hai CRON[25523]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Dec 24 22:30:01 srv4hai CRON[25524]: (root) CMD (/etc/linetest/linetest.sh)
Dec 24 22:30:01 srv4hai CRON[25525]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Dec 24 22:30:01 srv4hai common[25527]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/meter.sh
Dec 24 22:30:01 srv4hai meter.sh: SHELLY meter.sh started
Dec 24 22:30:02 srv4hai meter.sh: SHELLY 'shelly-29/KÜ Schalter Herdzeile' - type: 'shelly-i3' ignoring
Dec 24 22:30:02 srv4hai meter.sh: SHELLY 'shelly-28/KÜ Schalter Nasszeile' - type: 'shelly-i3' ignoring
Dec 24 22:30:06 srv4hai meter.sh: SHELLY 'shelly-13/Thomas Taster' - type: 'shelly-BTN' ignoring
Dec 24 22:30:06 srv4hai meter.sh: SHELLY 'shelly-14/AUS-Schalter' - type: 'shelly-BTN' ignoring
Dec 24 22:30:06 srv4hai meter.sh: SHELLY 'shelly-37/TB Klima' - type: 'shelly-ht' ignoring
Dec 24 22:30:06 srv4hai meter.sh: SHELLY 'shelly-38/Wetterwerte Innen' - status: 'OOS' ignoring
Dec 24 22:30:06 srv4hai meter.sh: SHELLY 'shelly-39/Wetterwerte Balkon' - type: 'shelly-ht-plus' ignoring
Dec 24 22:30:09 srv4hai meter.sh: SHELLY 'shelly-18/18-Test Farbige LED':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-15/15-Test Weiss':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-30/30-TBD' - status: 'OOS' ignoring
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-31/31-Test i3' - status: 'OOS' ignoring
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-32/32-TBD' - status: 'OOS' ignoring
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-33/33-TBD' - status: 'OOS' ignoring
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-36/36-TBD' - status: 'OOS' ignoring
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-09a/Inbetriebnahme - 20210720 - Defekt' - status: 'defect' ignoring
Dec 24 22:30:11 srv4hai mosquitto[29419]: Saving in-memory database to /var/lib/mosquitto/mosquitto.db.
Dec 24 22:30:11 srv4hai meter.sh: SHELLY 'shelly-09b/Sophie Licht - 20230404 - Defekt' - status: 'defect' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-26/20230404 - kein WLAN' - status: 'defect' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-27/Schalter Herdzeile - Defekt WLAN 20230417' - status: 'defect' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-21/Badezimmer - 20230720 - WLAN Problem' - status: 'defect' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-11/Ausgangstransistor defekt 20230722' - status: 'defect' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-01a/WZ Licht Esstisch - 20241208 kein WLAN' - status: 'DFC' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-43/43-tbd' - status: 'OOS' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-44/44-TBD' - status: 'OOS' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-46/46-TBD' - status: 'OOS' ignoring
Dec 24 22:30:12 srv4hai meter.sh: SHELLY 'shelly-48/BT GW 1' - status: 'OOS' ignoring
Dec 24 22:30:14 srv4hai meter.sh: SHELLY 'shelly-50/50-TBD':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Dec 24 22:30:14 srv4hai meter.sh: SHELLY 'shelly-59/Werkstatt Schalter Kellertür' - type: 'shelly-i3' ignoring
Dec 24 22:30:15 srv4hai common[26524]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_shelly.sh
Dec 24 22:30:15 srv4hai check_shelly.sh: SHELLY last scenery executed: 'vorraumOn'
Dec 24 22:30:15 srv4hai check_shelly.sh: SHELLY found '9' active shelly outputs
Dec 24 22:30:15 srv4hai meter.sh: SHELLY meter.sh ended
Dec 24 22:30:21 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26551, secured, session=
Dec 24 22:30:21 srv4hai systemd[1]: Started Run anacron jobs.
Dec 24 22:30:21 srv4hai dovecot: imap(rozumm)<26551>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 24 22:30:21 srv4hai anacron[26552]: Anacron 2.3 started on 2024-12-24
Dec 24 22:30:21 srv4hai anacron[26552]: Normal exit (0 jobs run)
Dec 24 22:30:21 srv4hai systemd[1]: anacron.service: Succeeded.
Dec 24 22:30:26 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1735075826 - 'ping_SP_1'
Dec 24 22:30:26 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Dec 24 22:30:46 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1735075846 - 'ping_SP_0'
Dec 24 22:30:46 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 24 22:30:48 srv4hai dhcpd[4995]: uid lease 192.168.12.56 for client cc:8d:a2:60:52:b8 is duplicate on 192.168.12.0/24
Dec 24 22:30:48 srv4hai dhcpd[4995]: DHCPDISCOVER from cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:48 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.101 to cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:50 srv4hai dhcpd[4995]: uid lease 192.168.12.56 for client cc:8d:a2:60:52:b8 is duplicate on 192.168.12.0/24
Dec 24 22:30:50 srv4hai dhcpd[4995]: DHCPDISCOVER from cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:50 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.101 to cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:50 srv4hai dhcpd[4995]: uid lease 192.168.12.56 for client cc:8d:a2:60:52:b8 is duplicate on 192.168.12.0/24
Dec 24 22:30:50 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 24 22:30:50 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.101
Dec 24 22:30:50 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-01
Dec 24 22:30:50 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.101 (192.168.12.252) from cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:50 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.101 to cc:8d:a2:60:52:b8 via enp2s0
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f9348081920 192.168.12.252#52789/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-01.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#47585/key rndc-key: signer "rndc-key" approved
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#47585/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-01.viehai.at' TXT
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#47585/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-01.viehai.at' TXT "31166740fe21d7ece7561d62986d2b8644"
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#47585/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-01.viehai.at' A
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#47585/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-01.viehai.at' A 192.168.12.101
Dec 24 22:30:50 srv4hai dhcpd[4995]: Added new forward map from shelly-01.viehai.at to 192.168.12.101
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f9350166000 192.168.12.252#57575/key rndc-key: signer "rndc-key" approved
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f9350166000 192.168.12.252#57575/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '101.12.168.192.in-addr.arpa' PTR
Dec 24 22:30:50 srv4hai named[30406]: client @0x7f9350166000 192.168.12.252#57575/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '101.12.168.192.in-addr.arpa' PTR shelly-01.viehai.at.
Dec 24 22:30:50 srv4hai dhcpd[4995]: Added reverse map from 101.12.168.192.in-addr.arpa. to shelly-01.viehai.at
Dec 24 22:30:50 srv4hai common[26595]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 24 22:30:50 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-01' - shIP/2:'' perform:'conf'
Dec 24 22:30:51 srv4hai postfix/anvil[25401]: statistics: max connection rate 1/60s for (smtp:80.94.95.239) at Dec 24 22:27:26
Dec 24 22:30:51 srv4hai postfix/anvil[25401]: statistics: max connection count 1 for (smtp:80.94.95.239) at Dec 24 22:27:26
Dec 24 22:30:51 srv4hai postfix/anvil[25401]: statistics: max cache size 1 at Dec 24 22:27:26
Dec 24 22:30:51 srv4hai mosquitto[29419]: New connection from 192.168.12.101 on port 1883.
Dec 24 22:30:51 srv4hai mosquitto[29419]: Client shelly1minig3-cc8da26052b8 already connected, closing old connection.
Dec 24 22:30:51 srv4hai mosquitto[29419]: Socket error on client shelly1minig3-cc8da26052b8, disconnecting.
Dec 24 22:30:51 srv4hai mosquitto[29419]: New client connected from 192.168.12.101 as shelly1minig3-cc8da26052b8 (c1, k60, u'shmqtt').
Dec 24 22:30:51 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-01' - shIP/2:'192.168.12.101'
Dec 24 22:30:51 srv4hai conf_shelly.sh: SHELLY: usage/name 'WZ Licht Esstisch' already set for '192.168.12.101'
Dec 24 22:31:01 srv4hai CRON[26642]: (root) CMD (/etc/linetest/linetest.sh)
Dec 24 22:31:21 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26651, secured, session=<3GqZ1QoqzLQAAAAAAAAAAAAAAAAAAAAB>
Dec 24 22:31:21 srv4hai dovecot: imap(rozumm)<26651><3GqZ1QoqzLQAAAAAAAAAAAAAAAAAAAAB>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 24 22:31:22 srv4hai postfix/smtpd[26652]: connect from unknown[80.94.95.239]
Dec 24 22:31:25 srv4hai postfix/smtpd[26652]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 24 22:31:26 srv4hai postfix/smtpd[26652]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Dec 24 22:31:39 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1735075899 - 'ping_SP_1'
Dec 24 22:31:39 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Dec 24 22:31:59 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1735075919 - 'ping_SP_0'
Dec 24 22:31:59 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 24 22:32:01 srv4hai CRON[26703]: (root) CMD (/etc/linetest/linetest.sh)
Dec 24 22:32:21 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26715, secured, session=
Dec 24 22:32:21 srv4hai dovecot: imap(rozumm)<26715>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 24 22:32:41 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Dec 24 22:32:42 srv4hai fetchmail[1651]: 60063 messages (60063 seen) for tmiglinci at mail.akis.at (640704278 octets).
Dec 24 22:32:42 srv4hai fetchmail[1651]: 11278 messages (11278 seen) for srozum at mail.akis.at (1351758903 octets).
Dec 24 22:32:43 srv4hai fetchmail[1651]: 28030 messages (28030 seen) for mrozum at mail.akis.at (520016902 octets).
Dec 24 22:32:43 srv4hai fetchmail[1651]: 1161 messages (1161 seen) for admin@bastelstube.wien at pop3.world4you.com (52202045 octets).
Dec 24 22:32:53 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1735075973 - 'ping_SP_1'
Dec 24 22:32:53 srv4hai ping_SP[28547]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Dec 24 22:33:01 srv4hai CRON[26747]: (root) CMD (/etc/linetest/linetest.sh)
Dec 24 22:33:12 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1735075992 - 'ping_SP_0'
Dec 24 22:33:12 srv4hai ping_SP[25935]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 24 22:33:21 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=26771, secured, session=
Dec 24 22:33:21 srv4hai dovecot: imap(rozumm)<26771>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Dec 24 22:33:33 srv4hai dhcpd[4995]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:33 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:33 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:33 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Dec 24 22:33:33 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Dec 24 22:33:33 srv4hai common[26775]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:43 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Dec 24 22:33:43 srv4hai common[26791]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 24 22:33:43 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:43 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:43 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:43 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Dec 24 22:33:43 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Dec 24 22:33:43 srv4hai common[26804]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:43 srv4hai ping_SP[26791]: SHELLY ping_SP.sh started from smartphone, user:'root'
Dec 24 22:33:50 srv4hai postfix/smtpd[26828]: connect from unknown[119.96.131.8]
Dec 24 22:33:51 srv4hai postfix/smtpd[26828]: warning: unknown[119.96.131.8]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 24 22:33:51 srv4hai postfix/smtpd[26828]: disconnect from unknown[119.96.131.8] ehlo=1 auth=0/1 quit=1 commands=2/3
Dec 24 22:33:53 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Dec 24 22:33:53 srv4hai common[26838]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 24 22:33:53 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:53 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 24 22:33:53 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:53 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Dec 24 22:33:53 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Dec 24 22:33:53 srv4hai named[30406]: client @0x7f933805ba90 192.168.12.252#53613/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 24 22:33:53 srv4hai common[26854]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 24 22:33:53 srv4hai common[26858]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Dec 24 22:33:53 srv4hai ping_SP[26838]: SHELLY ping_SP.sh started from smartphone, user:'root'
Dec 24 22:33:53 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'80'=1735076033-1735075953
Dec 24 22:33:53 srv4hai check_presence[26854]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Dec 24 22:33:54 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'81'=1735076034-1735075953
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Dec 24 22:33:54 srv4hai check_presence[26854]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Dec 24 22:33:54 srv4hai activate_scenario[26984]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Dec 24 22:33:54 srv4hai activate_scenario[26984]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished