activate_scenario.sh
Sat Sep 14 19:44:52 CEST 2024
scenario: vorraumOn
phoneName: 1

'night' <--> 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/09/14/_0.log)

vorher: 19:44:52.084

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: 19:44:52.204
Dauer: 120 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 19:44:52.065
nachher: 19:44:52.210
Dauer : 146 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/09/14//scene_vorraumOn_20240914_194452.txt'


syslog

Sep 14 19:41:01 srv4hai CRON[10832]: (root) CMD (/etc/linetest/linetest.sh)
Sep 14 19:41:04 srv4hai meter.sh: SHELLY 'shelly-58/Werkstatt Licht':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Sep 14 19:41:04 srv4hai meter.sh: SHELLY 'shelly-59/Werkstatt Schalter Kellertür' - type: 'shelly-i3' ignoring
Sep 14 19:41:04 srv4hai common[10907]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_shelly.sh
Sep 14 19:41:04 srv4hai check_shelly.sh: SHELLY last scenery executed: 'pcOFF'
Sep 14 19:41:04 srv4hai check_shelly.sh: SHELLY found '10' active shelly outputs
Sep 14 19:41:04 srv4hai meter.sh: SHELLY meter.sh ended
Sep 14 19:41:46 srv4hai dhcpd[30731]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Sep 14 19:41:46 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Sep 14 19:41:46 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Sep 14 19:41:46 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.122
Sep 14 19:41:46 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-22
Sep 14 19:41:46 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Sep 14 19:41:46 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f93506df2d0 192.168.12.252#51827/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#58445/key rndc-key: signer "rndc-key" approved
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#58445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#58445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#58445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#58445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Sep 14 19:41:46 srv4hai dhcpd[30731]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#60169/key rndc-key: signer "rndc-key" approved
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#60169/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Sep 14 19:41:46 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#60169/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Sep 14 19:41:46 srv4hai dhcpd[30731]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Sep 14 19:41:46 srv4hai common[10934]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Sep 14 19:41:46 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Sep 14 19:41:47 srv4hai mosquitto[18551]: New connection from 192.168.12.122 on port 1883.
Sep 14 19:41:47 srv4hai mosquitto[18551]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC' already set for '192.168.12.122'
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' out:0 ON : MISSING
Sep 14 19:41:47 srv4hai conf_shelly.sh: SHELLY:'shelly-22' out:0 OFF: MISSING
Sep 14 19:41:52 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:41:52 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:41:56 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:41:56 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:42:01 srv4hai CRON[11065]: (root) CMD (/etc/linetest/linetest.sh)
Sep 14 19:42:02 srv4hai nmbd[2172]: [2024/09/14 19:42:02.510911,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
Sep 14 19:42:02 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
Sep 14 19:42:02 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
Sep 14 19:42:02 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
Sep 14 19:42:02 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726335722 - 'ping_SP_0'
Sep 14 19:42:02 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 14 19:42:16 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:42:16 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:42:18 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Sep 14 19:42:18 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Sep 14 19:42:18 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Sep 14 19:42:18 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:42:18 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f9310088a50 192.168.12.252#34593/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#39921/key rndc-key: signer "rndc-key" approved
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#39921/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#39921/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#39921/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f932c03e370 192.168.12.252#39921/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Sep 14 19:42:18 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#55809/key rndc-key: signer "rndc-key" approved
Sep 14 19:42:18 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#55809/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Sep 14 19:42:18 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#55809/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '5.12.168.192.in-addr.arpa' PTR btscan1.viehai.at.
Sep 14 19:42:18 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Sep 14 19:42:18 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Sep 14 19:42:19 srv4hai postfix/smtpd[11095]: connect from unknown[94.141.120.39]
Sep 14 19:42:20 srv4hai postfix/smtpd[11095]: warning: unknown[94.141.120.39]: SASL LOGIN authentication failed: Invalid authentication mechanism
Sep 14 19:42:20 srv4hai postfix/smtpd[11095]: lost connection after AUTH from unknown[94.141.120.39]
Sep 14 19:42:20 srv4hai postfix/smtpd[11095]: disconnect from unknown[94.141.120.39] ehlo=1 auth=0/1 commands=1/2
Sep 14 19:43:01 srv4hai CRON[11105]: (root) CMD (/etc/linetest/linetest.sh)
Sep 14 19:43:05 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726335785 - 'ping_SP_0'
Sep 14 19:43:05 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 14 19:43:21 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Sep 14 19:43:23 srv4hai fetchmail[1651]: 52321 messages (52321 seen) for tmiglinci at mail.akis.at (11474597 octets).
Sep 14 19:43:24 srv4hai fetchmail[1651]: 10722 messages (10722 seen) for srozum at mail.akis.at (1301802736 octets).
Sep 14 19:43:26 srv4hai fetchmail[1651]: 25919 messages (25919 seen) for mrozum at mail.akis.at (214707981 octets).
Sep 14 19:43:26 srv4hai fetchmail[1651]: 1143 messages (1143 seen) for admin@bastelstube.wien at pop3.world4you.com (51639660 octets).
Sep 14 19:44:01 srv4hai CRON[11159]: (root) CMD (/etc/linetest/linetest.sh)
Sep 14 19:44:09 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726335849 - 'ping_SP_0'
Sep 14 19:44:09 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 14 19:44:27 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:27 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:31 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:31 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:31 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Sep 14 19:44:31 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Sep 14 19:44:31 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Sep 14 19:44:31 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:31 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f93506e3340 192.168.12.252#50303/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#44729/key rndc-key: signer "rndc-key" approved
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#44729/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#44729/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#44729/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#44729/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Sep 14 19:44:31 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Sep 14 19:44:31 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#48505/key rndc-key: signer "rndc-key" approved
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#48505/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Sep 14 19:44:31 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#48505/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '5.12.168.192.in-addr.arpa' PTR btscan1.viehai.at.
Sep 14 19:44:31 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Sep 14 19:44:31 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Sep 14 19:44:51 srv4hai common[11221]: SHELLY common.sh inkludiert von ./bt_scan.sh
Sep 14 19:44:51 srv4hai bt_scan[11221]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Sep 14 19:44:51 srv4hai bt_scan[11221]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Sep 14 19:44:51 srv4hai common[11252]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 14 19:44:51 srv4hai ping_SP[11252]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Sep 14 19:44:51 srv4hai common[11273]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 14 19:44:51 srv4hai common.sh: SHELLY: BTstatus[0]:'null' dweetAge:'17'=1726335891-1726335874
Sep 14 19:44:51 srv4hai check_presence[11273]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and null//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Sep 14 19:44:51 srv4hai check_presence[11273]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Sep 14 19:44:51 srv4hai common.sh: SHELLY: BTstatus[1]:'null' dweetAge:'17'=1726335891-1726335874
Sep 14 19:44:51 srv4hai check_presence[11273]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and null//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Sep 14 19:44:51 srv4hai common.sh: SHELLY: BTstatus[2]:'null' dweetAge:'17'=1726335891-1726335874
Sep 14 19:44:51 srv4hai check_presence[11273]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and null//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 14 19:44:51 srv4hai check_presence[11273]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Sep 14 19:44:52 srv4hai check_presence[11273]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_pcOFF) - sceneONsmall(vorraumOn)
Sep 14 19:44:52 srv4hai check_presence[11273]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Sep 14 19:44:52 srv4hai activate_scenario[11417]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 14 19:44:52 srv4hai activate_scenario[11417]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished