activate_scenario.sh
Mon Dec 23 21:05:24 CET 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'

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

vorher: 21:05:24.427

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: 21:05:24.478
Dauer: 51 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 21:05:24.380
nachher: 21:05:24.484
Dauer : 106 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/23//scene_vorraumOn_20241223_210524.txt'


syslog

Dec 23 21:02:01 srv4hai CRON[3330]: (root) CMD (/etc/linetest/linetest.sh)
Dec 23 21:02:03 srv4hai postfix/smtpd[3335]: connect from unknown[154.203.197.233]
Dec 23 21:02:03 srv4hai postfix/smtpd[3335]: warning: unknown[154.203.197.233]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 23 21:02:03 srv4hai postfix/smtpd[3335]: disconnect from unknown[154.203.197.233] ehlo=1 auth=0/1 quit=1 commands=2/3
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPDISCOVER from 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.27 to 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPDISCOVER from 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.27 to 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 23 21:02:19 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.27
Dec 23 21:02:19 srv4hai dhcpd[4995]: execute_statement argv[2] = tvsz
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.27 (192.168.12.252) from 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.27 to 70:b1:3d:9f:36:ea via enp2s0
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f935010f2a0 192.168.12.252#52041/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: tvsz.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#47373/key rndc-key: signer "rndc-key" approved
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#47373/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'tvsz.viehai.at' TXT
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#47373/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'tvsz.viehai.at' TXT "3189601b07a6e5c1515a2fd330306e89ab"
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#47373/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'tvsz.viehai.at' A
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f93506d04d0 192.168.12.252#47373/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'tvsz.viehai.at' A 192.168.12.27
Dec 23 21:02:19 srv4hai dhcpd[4995]: Added new forward map from tvsz.viehai.at to 192.168.12.27
Dec 23 21:02:19 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.27'(27) device 'tvsz'
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#44859/key rndc-key: signer "rndc-key" approved
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#44859/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '27.12.168.192.in-addr.arpa' PTR
Dec 23 21:02:19 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#44859/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '27.12.168.192.in-addr.arpa' PTR tvsz.viehai.at.
Dec 23 21:02:19 srv4hai dhcpd[4995]: Added reverse map from 27.12.168.192.in-addr.arpa. to tvsz.viehai.at
Dec 23 21:02:19 srv4hai dhcpcommit.sh: SHELLY device 'tvsz' known and accepted
Dec 23 21:02:37 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:37 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:39 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:39 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:39 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 23 21:02:39 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.5
Dec 23 21:02:39 srv4hai dhcpd[4995]: execute_statement argv[2] = btscan1
Dec 23 21:02:39 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:39 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f9310079c50 192.168.12.252#32857/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f93380793b0 192.168.12.252#37015/key rndc-key: signer "rndc-key" approved
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f93380793b0 192.168.12.252#37015/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f93380793b0 192.168.12.252#37015/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f93380793b0 192.168.12.252#37015/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f93380793b0 192.168.12.252#37015/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Dec 23 21:02:39 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Dec 23 21:02:39 srv4hai dhcpd[4995]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#34351/key rndc-key: signer "rndc-key" approved
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#34351/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Dec 23 21:02:39 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#34351/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.
Dec 23 21:02:39 srv4hai dhcpd[4995]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Dec 23 21:02:39 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Dec 23 21:02:52 srv4hai common[3362]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 23 21:03:01 srv4hai CRON[3381]: (root) CMD (/etc/linetest/linetest.sh)
Dec 23 21:03:02 srv4hai bt_scan[3362]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Dec 23 21:03:02 srv4hai bt_scan[3362]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Dec 23 21:03:11 srv4hai ping_SP[23605]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1734984191 - 'ping_SP_0'
Dec 23 21:03:11 srv4hai ping_SP[23605]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 23 21:04:01 srv4hai CRON[3438]: (root) CMD (/etc/linetest/linetest.sh)
Dec 23 21:04:10 srv4hai postfix/smtpd[3445]: connect from unknown[80.94.95.239]
Dec 23 21:04:13 srv4hai postfix/smtpd[3445]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 23 21:04:14 srv4hai postfix/smtpd[3445]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Dec 23 21:04:18 srv4hai common[3454]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 23 21:04:24 srv4hai ping_SP[23605]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1734984264 - 'ping_SP_0'
Dec 23 21:04:24 srv4hai ping_SP[23605]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 23 21:04:28 srv4hai bt_scan[3454]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Dec 23 21:04:28 srv4hai bt_scan[3454]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Dec 23 21:04:28 srv4hai common[3503]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 23 21:04:29 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'11'=1734984269-1734984258
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Dec 23 21:04:29 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'11'=1734984269-1734984258
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_pcOFF) - sceneOFF(offAll)
Dec 23 21:04:29 srv4hai check_presence[3503]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Dec 23 21:04:36 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:36 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:38 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:38 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:38 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 23 21:04:38 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.5
Dec 23 21:04:38 srv4hai dhcpd[4995]: execute_statement argv[2] = btscan1
Dec 23 21:04:38 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:38 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f931001f430 192.168.12.252#48019/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#46991/key rndc-key: signer "rndc-key" approved
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#46991/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#46991/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#46991/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#46991/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Dec 23 21:04:38 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Dec 23 21:04:38 srv4hai dhcpd[4995]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300b2620 192.168.12.252#60419/key rndc-key: signer "rndc-key" approved
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300b2620 192.168.12.252#60419/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Dec 23 21:04:38 srv4hai named[30406]: client @0x7f93300b2620 192.168.12.252#60419/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.
Dec 23 21:04:38 srv4hai dhcpd[4995]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Dec 23 21:04:38 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Dec 23 21:04:40 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 23 21:04:40 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.5
Dec 23 21:04:40 srv4hai dhcpd[4995]: execute_statement argv[2] = btscan1
Dec 23 21:04:40 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:40 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f935063c890 192.168.12.252#49403/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#41121/key rndc-key: signer "rndc-key" approved
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#41121/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#41121/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#41121/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f932c0b5090 192.168.12.252#41121/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Dec 23 21:04:40 srv4hai dhcpd[4995]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Dec 23 21:04:40 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#47409/key rndc-key: signer "rndc-key" approved
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#47409/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Dec 23 21:04:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#47409/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.
Dec 23 21:04:40 srv4hai dhcpd[4995]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Dec 23 21:04:40 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Dec 23 21:05:01 srv4hai CRON[3639]: (root) CMD (/etc/linetest/linetest.sh)
Dec 23 21:05:13 srv4hai common[3647]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 23 21:05:23 srv4hai bt_scan[3647]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Dec 23 21:05:23 srv4hai bt_scan[3647]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Dec 23 21:05:23 srv4hai common[3681]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 23 21:05:23 srv4hai ping_SP[3681]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Dec 23 21:05:23 srv4hai common[3702]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 23 21:05:24 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'11'=1734984324-1734984313
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Dec 23 21:05:24 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'11'=1734984324-1734984313
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/LIVE) (BT/__)' - anyPresent: 'true' - otherPresent:'true'
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_pcOFF) - sceneONsmall(vorraumOn)
Dec 23 21:05:24 srv4hai check_presence[3702]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Dec 23 21:05:24 srv4hai activate_scenario[3816]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Dec 23 21:05:24 srv4hai activate_scenario[3816]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished