activate_scenario.sh
Sat Jul 13 18:10:42 CEST 2024
scenario: vorraumOn
phoneName: 1

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

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/07/13/_0.log)

vorher: 18:10:42.914

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: 18:10:43.009
Dauer: 95 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 18:10:42.896
nachher: 18:10:43.015
Dauer : 120 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/07/13//scene_vorraumOn_20240713_181042.txt'


syslog

Jul 13 18:07:01 srv4hai CRON[13195]: (root) CMD (/etc/linetest/linetest.sh)
Jul 13 18:07:07 srv4hai mosquitto[18551]: Client shelly1-98CDAC2D5A11 has exceeded timeout, disconnecting.
Jul 13 18:07:07 srv4hai mosquitto[18551]: Socket error on client shelly1-98CDAC2D5A11, disconnecting.
Jul 13 18:07:21 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1720886841 - 'ping_SP_0'
Jul 13 18:07:21 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jul 13 18:07:22 srv4hai postfix/smtpd[13235]: warning: database /etc/aliases.db is older than source file /etc/aliases
Jul 13 18:07:22 srv4hai postfix/smtpd[13235]: connect from 94-154-35-194.virtualine.org[94.154.35.194]
Jul 13 18:07:22 srv4hai postfix/smtpd[13235]: warning: 94-154-35-194.virtualine.org[94.154.35.194]: SASL LOGIN authentication failed: Invalid authentication mechanism
Jul 13 18:07:23 srv4hai postfix/smtpd[13235]: disconnect from 94-154-35-194.virtualine.org[94.154.35.194] ehlo=1 auth=0/1 quit=1 commands=2/3
Jul 13 18:08:01 srv4hai CRON[13241]: (root) CMD (/etc/linetest/linetest.sh)
Jul 13 18:08:24 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jul 13 18:08:24 srv4hai fetchmail[1651]: 48619 messages (48619 seen) for tmiglinci at mail.akis.at (-276417901 octets).
Jul 13 18:08:25 srv4hai fetchmail[1651]: 10446 messages (10446 seen) for srozum at mail.akis.at (1253348764 octets).
Jul 13 18:08:25 srv4hai fetchmail[1651]: 24716 messages (24716 seen) for mrozum at mail.akis.at (29368989 octets).
Jul 13 18:08:26 srv4hai fetchmail[1651]: 1139 messages (1139 seen) for admin@bastelstube.wien at pop3.world4you.com (51570784 octets).
Jul 13 18:08:34 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1720886914 - 'ping_SP_0'
Jul 13 18:08:34 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jul 13 18:08:40 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=13275, secured, session=<9MuSNSMdwqoAAAAAAAAAAAAAAAAAAAAB>
Jul 13 18:08:40 srv4hai dovecot: imap(miglincit)<13275><9MuSNSMdwqoAAAAAAAAAAAAAAAAAAAAB>: Logged out in=137 out=1053 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jul 13 18:09:01 srv4hai CRON[13279]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Jul 13 18:09:01 srv4hai CRON[13280]: (root) CMD (/etc/linetest/linetest.sh)
Jul 13 18:09:01 srv4hai systemd[1]: Starting Clean php session files...
Jul 13 18:09:01 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Jul 13 18:09:01 srv4hai systemd[1]: Started Clean php session files.
Jul 13 18:09:47 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1720886987 - 'ping_SP_0'
Jul 13 18:09:47 srv4hai ping_SP[22702]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jul 13 18:10:01 srv4hai CRON[13406]: (root) CMD (/etc/linetest/linetest.sh)
Jul 13 18:10:01 srv4hai CRON[13407]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Jul 13 18:10:01 srv4hai common[13409]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/meter.sh
Jul 13 18:10:01 srv4hai meter.sh: SHELLY meter.sh started
Jul 13 18:10:01 srv4hai meter.sh: SHELLY 'shelly-29/Schalter Herdzeile' - type: 'shelly-i3' ignoring
Jul 13 18:10:02 srv4hai meter.sh: SHELLY 'shelly-28/Schalter Nasszeile' - type: 'shelly-i3' ignoring
Jul 13 18:10:05 srv4hai meter.sh: SHELLY 'shelly-19/Fernsehlampe groß':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Jul 13 18:10:07 srv4hai meter.sh: SHELLY 'shelly-13/Thomas Taster' - type: 'shelly-BTN' ignoring
Jul 13 18:10:07 srv4hai meter.sh: SHELLY 'shelly-14/AUS-Schalter' - type: 'shelly-BTN' ignoring
Jul 13 18:10:07 srv4hai meter.sh: SHELLY 'shelly-37/Klima Büro' - type: 'shelly-ht' ignoring
Jul 13 18:10:07 srv4hai meter.sh: SHELLY 'shelly-38/Wetterwerte Innen' - status: 'OOS' ignoring
Jul 13 18:10:07 srv4hai meter.sh: SHELLY 'shelly-39/Wetterwerte Balkon' - type: 'shelly-ht-plus' ignoring
Jul 13 18:10:10 srv4hai meter.sh: SHELLY 'shelly-18/18-Test Farbige LED':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-15/15-Test Weiss':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-30/30-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-31/31-Test i3' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-32/32-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-33/33-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-36/36-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-47/47-plus1-Test' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-09a/Inbetriebnahme - 20210720 - Defekt' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-09b/Sophie Licht - 20230404 - Defekt' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-26/20230404 - kein WLAN' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-27/Schalter Herdzeile - Defekt WLAN 20230417' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-21/Badezimmer - 20230720 - WLAN Problem' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-11/Ausgangstransistor defekt 20230722' - status: 'defect' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-43/43-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-44/44-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-45/45-TBD' - status: 'OOS' ignoring
Jul 13 18:10:13 srv4hai meter.sh: SHELLY 'shelly-46/46-TBD' - status: 'OOS' ignoring
Jul 13 18:10:17 srv4hai meter.sh: SHELLY 'shelly-58/Werkstatt Licht':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Jul 13 18:10:17 srv4hai meter.sh: SHELLY 'shelly-59/Werkstatt Schalter Kellertür' - type: 'shelly-i3' ignoring
Jul 13 18:10:17 srv4hai common[14286]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_shelly.sh
Jul 13 18:10:18 srv4hai check_shelly.sh: SHELLY last scenery executed: 'pcON'
Jul 13 18:10:18 srv4hai check_shelly.sh: SHELLY found '5' active shelly outputs
Jul 13 18:10:18 srv4hai meter.sh: SHELLY meter.sh ended
Jul 13 18:10:31 srv4hai common[14310]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPDISCOVER from 98:cd:ac:2d:5a:11 via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.119 to 98:cd:ac:2d:5a:11 via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.119
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-19
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.119 (192.168.12.252) from 98:cd:ac:2d:5a:11 via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.119 to 98:cd:ac:2d:5a:11 via enp2s0
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f935011da30 192.168.12.252#35931/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-19.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#34741/key rndc-key: signer "rndc-key" approved
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#34741/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-19.viehai.at' TXT
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#34741/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-19.viehai.at' TXT "0058d808d26aa46bf6275641574f8413b8"
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#34741/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-19.viehai.at' A
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#34741/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-19.viehai.at' A 192.168.12.119
Jul 13 18:10:33 srv4hai dhcpd[30731]: Added new forward map from shelly-19.viehai.at to 192.168.12.119
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#35443/key rndc-key: signer "rndc-key" approved
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#35443/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '119.12.168.192.in-addr.arpa' PTR
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f931c094600 192.168.12.252#35443/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.
Jul 13 18:10:33 srv4hai dhcpd[30731]: Added reverse map from 119.12.168.192.in-addr.arpa. to shelly-19.viehai.at
Jul 13 18:10:33 srv4hai common[14324]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Jul 13 18:10:33 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-19' - shIP/2:'' perform:'conf'
Jul 13 18:10:33 srv4hai dhcpd[30731]: uid lease 192.168.12.40 for client ec:da:3b:c6:1d:3c is duplicate on 192.168.12.0/24
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPDISCOVER from ec:da:3b:c6:1d:3c via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.150 to ec:da:3b:c6:1d:3c via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: uid lease 192.168.12.40 for client ec:da:3b:c6:1d:3c is duplicate on 192.168.12.0/24
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.150
Jul 13 18:10:33 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-50
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.150 (192.168.12.252) from ec:da:3b:c6:1d:3c via enp2s0
Jul 13 18:10:33 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.150 to ec:da:3b:c6:1d:3c via enp2s0
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f935063c890 192.168.12.252#59817/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-50.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f934c037ed0 192.168.12.252#38695/key rndc-key: signer "rndc-key" approved
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f934c037ed0 192.168.12.252#38695/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-50.viehai.at' TXT
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f934c037ed0 192.168.12.252#38695/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-50.viehai.at' TXT "31ddfcf6e79cbbdf4e55e03247020075f9"
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f934c037ed0 192.168.12.252#38695/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-50.viehai.at' A
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f934c037ed0 192.168.12.252#38695/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-50.viehai.at' A 192.168.12.150
Jul 13 18:10:33 srv4hai dhcpd[30731]: Added new forward map from shelly-50.viehai.at to 192.168.12.150
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f933805ba90 192.168.12.252#44159/key rndc-key: signer "rndc-key" approved
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f933805ba90 192.168.12.252#44159/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '150.12.168.192.in-addr.arpa' PTR
Jul 13 18:10:33 srv4hai named[30406]: client @0x7f933805ba90 192.168.12.252#44159/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '150.12.168.192.in-addr.arpa' PTR shelly-50.viehai.at.
Jul 13 18:10:33 srv4hai dhcpd[30731]: Added reverse map from 150.12.168.192.in-addr.arpa. to shelly-50.viehai.at
Jul 13 18:10:33 srv4hai common[14337]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Jul 13 18:10:33 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-50' - shIP/2:'' perform:'conf'
Jul 13 18:10:33 srv4hai mosquitto[18551]: New connection from 192.168.12.150 on port 1883.
Jul 13 18:10:33 srv4hai mosquitto[18551]: Client shellypmminig3-ecda3bc61d3c already connected, closing old connection.
Jul 13 18:10:33 srv4hai mosquitto[18551]: Socket error on client shellypmminig3-ecda3bc61d3c, disconnecting.
Jul 13 18:10:33 srv4hai mosquitto[18551]: New client connected from 192.168.12.150 as shellypmminig3-ecda3bc61d3c (c1, k60, u'shmqtt').
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-50' - shIP/2:'192.168.12.150'
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY: usage/name '50-TBD' already set for '192.168.12.150'
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-50' no WebHook
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-19' - shIP/2:'192.168.12.119'
Jul 13 18:10:34 srv4hai mosquitto[18551]: New connection from 192.168.12.119 on port 1883.
Jul 13 18:10:34 srv4hai mosquitto[18551]: New client connected from 192.168.12.119 as shelly1-98CDAC2D5A11 (c1, k60, u'shmqtt').
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY: usage/name 'Fernsehlampe groß' already set for '192.168.12.119'
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(0)' - allready transmitted - skipping
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(1)' - allready transmitted - skipping
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(2)' - allready transmitted - skipping
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' 'conf(3)' - allready transmitted - skipping
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' out:0 ON : MISSING
Jul 13 18:10:34 srv4hai conf_shelly.sh: SHELLY:'shelly-19' out:0 OFF: MISSING
Jul 13 18:10:42 srv4hai bt_scan[14310]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Jul 13 18:10:42 srv4hai bt_scan[14310]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Jul 13 18:10:42 srv4hai common[14505]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jul 13 18:10:42 srv4hai ping_SP[14505]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Jul 13 18:10:42 srv4hai common[14526]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jul 13 18:10:42 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'10'=1720887042-1720887032
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Jul 13 18:10:42 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'10'=1720887042-1720887032
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Jul 13 18:10:42 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'10'=1720887042-1720887032
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.Yes
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/LIVE) (BT/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_pcON) - sceneONsmall(vorraumOn)
Jul 13 18:10:42 srv4hai check_presence[14526]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Jul 13 18:10:42 srv4hai activate_scenario[14672]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jul 13 18:10:42 srv4hai activate_scenario[14672]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished