activate_scenario.sh
Mon Jan 13 19:34:17 CET 2025
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/2025/01/13/_0.log)

vorher: 19:34:17.138

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:34:17.169
Dauer: 31 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 19:34:17.099
nachher: 19:34:17.176
Dauer : 78 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/01/13//scene_vorraumOn_20250113_193417.txt'


syslog

Jan 13 19:31:01 srv4hai CRON[10354]: (root) CMD (/etc/linetest/linetest.sh)
Jan 13 19:31:20 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Jan 13 19:31:21 srv4hai fetchmail[1651]: 61375 messages (61375 seen) for tmiglinci at mail.akis.at (734473216 octets).
Jan 13 19:31:22 srv4hai fetchmail[1651]: 11376 messages (11376 seen) for srozum at mail.akis.at (1357540282 octets).
Jan 13 19:31:22 srv4hai fetchmail[1651]: 28313 messages (28313 seen) for mrozum at mail.akis.at (567517591 octets).
Jan 13 19:31:23 srv4hai fetchmail[1651]: 1162 messages (1162 seen) for admin@bastelstube.wien at pop3.world4you.com (52206525 octets).
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Jan 13 19:31:29 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0
Jan 13 19:31:29 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Jan 13 19:31:29 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Jan 13 19:31:29 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Jan 13 19:31:29 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0
Jan 13 19:31:29 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f9348072b20 192.168.12.252#42395/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f934c0165b0 192.168.12.252#34321/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 13 19:31:29 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f932c0ce020 192.168.12.252#46093/key rndc-key: signer "rndc-key" approved
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f932c0ce020 192.168.12.252#46093/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f932c0ce020 192.168.12.252#46093/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f932c0ce020 192.168.12.252#46093/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f932c0ce020 192.168.12.252#46093/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: signer "rndc-key" approved
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Jan 13 19:31:29 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Jan 13 19:31:29 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: signer "rndc-key" approved
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '28.12.168.192.in-addr.arpa' PTR harmonyhub.viehai.at.
Jan 13 19:31:29 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: signer "rndc-key" approved
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Jan 13 19:31:29 srv4hai named[30406]: client @0x7f93506770d0 192.168.12.252#34693/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '28.12.168.192.in-addr.arpa' PTR harmonyhub.viehai.at.
Jan 13 19:31:29 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Jan 13 19:31:29 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Jan 13 19:31:29 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Jan 13 19:31:33 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736793093 - 'ping_SP_0'
Jan 13 19:31:33 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 13 19:31:51 srv4hai postfix/smtpd[10405]: connect from unknown[154.203.197.109]
Jan 13 19:31:51 srv4hai postfix/anvil[9161]: statistics: max connection rate 1/60s for (smtp:154.203.197.109) at Jan 13 19:27:33
Jan 13 19:31:51 srv4hai postfix/anvil[9161]: statistics: max connection count 1 for (smtp:154.203.197.109) at Jan 13 19:27:33
Jan 13 19:31:51 srv4hai postfix/anvil[9161]: statistics: max cache size 2 at Jan 13 19:28:27
Jan 13 19:31:51 srv4hai postfix/smtpd[10405]: warning: unknown[154.203.197.109]: SASL LOGIN authentication failed: Invalid authentication mechanism
Jan 13 19:31:51 srv4hai postfix/smtpd[10405]: lost connection after AUTH from unknown[154.203.197.109]
Jan 13 19:31:51 srv4hai postfix/smtpd[10405]: disconnect from unknown[154.203.197.109] ehlo=1 auth=0/1 commands=1/2
Jan 13 19:32:01 srv4hai CRON[10414]: (root) CMD (/etc/linetest/linetest.sh)
Jan 13 19:32:36 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736793156 - 'ping_SP_0'
Jan 13 19:32:36 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 13 19:32:50 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=10453, secured, session=
Jan 13 19:32:50 srv4hai dovecot: imap(miglincit)<10453>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 13 19:33:01 srv4hai CRON[10457]: (root) CMD (/etc/linetest/linetest.sh)
Jan 13 19:33:39 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736793219 - 'ping_SP_0'
Jan 13 19:33:39 srv4hai ping_SP[6212]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 13 19:34:01 srv4hai CRON[10497]: (root) CMD (/etc/linetest/linetest.sh)
Jan 13 19:34:06 srv4hai dhcpd[4995]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Jan 13 19:34:06 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 13 19:34:06 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 13 19:34:06 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.62
Jan 13 19:34:06 srv4hai dhcpd[4995]: execute_statement argv[2] = GreteS20
Jan 13 19:34:06 srv4hai common[10502]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Jan 13 19:34:06 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Jan 13 19:34:06 srv4hai common[10517]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jan 13 19:34:06 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Jan 13 19:34:06 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f934c0aebf0 192.168.12.252#48405/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f9338013780 192.168.12.252#56995/key rndc-key: signer "rndc-key" approved
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f9338013780 192.168.12.252#56995/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' TXT
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f9338013780 192.168.12.252#56995/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' TXT "318a9f974c0d8a2868f64397b15c3ed3a0"
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f9338013780 192.168.12.252#56995/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' A
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f9338013780 192.168.12.252#56995/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' A 192.168.12.62
Jan 13 19:34:06 srv4hai dhcpd[4995]: Added new forward map from GreteS20.viehai.at to 192.168.12.62
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#54869/key rndc-key: signer "rndc-key" approved
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#54869/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '62.12.168.192.in-addr.arpa' PTR
Jan 13 19:34:06 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#54869/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '62.12.168.192.in-addr.arpa' PTR GreteS20.viehai.at.
Jan 13 19:34:06 srv4hai dhcpd[4995]: Added reverse map from 62.12.168.192.in-addr.arpa. to GreteS20.viehai.at
Jan 13 19:34:06 srv4hai ping_SP[10517]: SHELLY ping_SP.sh started from smartphone, user:'root'
Jan 13 19:34:07 srv4hai common[10539]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jan 13 19:34:16 srv4hai common[10568]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jan 13 19:34:16 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'9'=1736793256-1736793247
Jan 13 19:34:16 srv4hai check_presence[10568]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Jan 13 19:34:16 srv4hai check_presence[10568]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Jan 13 19:34:16 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'9'=1736793256-1736793247
Jan 13 19:34:16 srv4hai check_presence[10568]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Jan 13 19:34:16 srv4hai check_presence[10568]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Jan 13 19:34:16 srv4hai check_presence[10568]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(BT/LIVE) (BT/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Jan 13 19:34:17 srv4hai check_presence[10568]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_buero_UM) - sceneONsmall(vorraumOn)
Jan 13 19:34:17 srv4hai check_presence[10568]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Jan 13 19:34:17 srv4hai activate_scenario[10685]: SHELLY last scenario: 'buero_UM' @ '191453' from 'scene_buero_UM_20250113_191453.txt' - delta=36
Jan 13 19:34:17 srv4hai activate_scenario[10685]:        dateutils.ddiff -i '%Y%m%d%H%M%s' 20250113193417 20250113191453  -f '%S'
Jan 13 19:34:17 srv4hai activate_scenario[10685]: SHELLY  new scenario: 'vorraumOn' different - GO ON
Jan 13 19:34:17 srv4hai activate_scenario[10685]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jan 13 19:34:17 srv4hai activate_scenario[10685]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished