activate_scenario.sh
Sat Feb 1 17:18:40 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/02/01/_0.log)

vorher: 17:18:40.586

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: 17:18:40.689
Dauer: 104 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 17:18:40.560
nachher: 17:18:40.696
Dauer : 135 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/02/01//scene_vorraumOn_20250201_171840.txt'


syslog

Feb  1 17:15:00 srv4hai common[19140]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:15:01 srv4hai CRON[19165]: (root) CMD (/etc/linetest/linetest.sh)
Feb  1 17:15:14 srv4hai postfix/anvil[18226]: statistics: max connection rate 1/60s for (smtp:92.118.39.63) at Feb  1 17:11:30
Feb  1 17:15:14 srv4hai postfix/anvil[18226]: statistics: max connection count 1 for (smtp:92.118.39.63) at Feb  1 17:11:30
Feb  1 17:15:14 srv4hai postfix/anvil[18226]: statistics: max cache size 2 at Feb  1 17:11:50
Feb  1 17:15:38 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738426538 - 'ping_SP_0'
Feb  1 17:15:38 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  1 17:15:50 srv4hai common[19241]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:15:50 srv4hai common[19265]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:00 srv4hai common[19291]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:00 srv4hai common[19315]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:00 srv4hai common[19339]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:01 srv4hai CRON[19364]: (root) CMD (/etc/linetest/linetest.sh)
Feb  1 17:16:02 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Feb  1 17:16:02 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Feb  1 17:16:02 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Feb  1 17:16:02 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:16:02 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#52541/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#52997/key rndc-key: signer "rndc-key" approved
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#52997/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#52997/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#52997/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#52997/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Feb  1 17:16:02 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Feb  1 17:16:02 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f9348048e40 192.168.12.252#60357/key rndc-key: signer "rndc-key" approved
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f9348048e40 192.168.12.252#60357/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Feb  1 17:16:02 srv4hai named[30406]: client @0x7f9348048e40 192.168.12.252#60357/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.
Feb  1 17:16:02 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Feb  1 17:16:02 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Feb  1 17:16:23 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=19391, secured, session=<+aT0+hYtmpsAAAAAAAAAAAAAAAAAAAAB>
Feb  1 17:16:23 srv4hai dovecot: imap(miglincit)<19391><+aT0+hYtmpsAAAAAAAAAAAAAAAAAAAAB>: Logged out in=137 out=1087 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  1 17:16:38 srv4hai postfix/smtpd[19407]: warning: hostname mail1.whauwant.com does not resolve to address 198.46.151.35: Name or service not known
Feb  1 17:16:38 srv4hai postfix/smtpd[19407]: connect from unknown[198.46.151.35]
Feb  1 17:16:43 srv4hai postfix/smtpd[19407]: warning: unknown[198.46.151.35]: SASL PLAIN authentication failed: 
Feb  1 17:16:43 srv4hai postfix/smtpd[19407]: disconnect from unknown[198.46.151.35] ehlo=1 auth=0/1 quit=1 commands=2/3
Feb  1 17:16:50 srv4hai common[19415]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:50 srv4hai common[19439]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:51 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738426611 - 'ping_SP_0'
Feb  1 17:16:51 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  1 17:16:59 srv4hai common[19479]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:16:59 srv4hai common[19503]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:17:00 srv4hai common[19527]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:17:01 srv4hai CRON[19553]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Feb  1 17:17:01 srv4hai CRON[19554]: (root) CMD (/etc/linetest/linetest.sh)
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Feb  1 17:17:30 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:17:30 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Feb  1 17:17:30 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Feb  1 17:17:30 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Feb  1 17:17:30 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:17:30 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f935063c890 192.168.12.252#33519/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f931c02c6e0 192.168.12.252#33165/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#60201/key rndc-key: signer "rndc-key" approved
Feb  1 17:17:30 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#60201/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#60201/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#60201/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f9350685ed0 192.168.12.252#60201/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: signer "rndc-key" approved
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Feb  1 17:17:30 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Feb  1 17:17:30 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: signer "rndc-key" approved
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/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.
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: signer "rndc-key" approved
Feb  1 17:17:30 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Feb  1 17:17:30 srv4hai named[30406]: client @0x7f93100d29e0 192.168.12.252#39259/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.
Feb  1 17:17:30 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Feb  1 17:17:30 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Feb  1 17:17:30 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Feb  1 17:17:50 srv4hai common[19600]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:17:50 srv4hai common[19624]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:17:53 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Feb  1 17:17:54 srv4hai fetchmail[1651]: 62711 messages (62711 seen) for tmiglinci at mail.akis.at (835099196 octets).
Feb  1 17:17:55 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=19658, secured, session=<0bpvABctqpsAAAAAAAAAAAAAAAAAAAAB>
Feb  1 17:17:55 srv4hai fetchmail[1651]: 11476 messages (11476 seen) for srozum at mail.akis.at (1365869858 octets).
Feb  1 17:17:55 srv4hai dovecot: imap(miglincit)<19658><0bpvABctqpsAAAAAAAAAAAAAAAAAAAAB>: Logged out in=137 out=1087 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  1 17:17:55 srv4hai fetchmail[1651]: 28673 messages (28673 seen) for mrozum at mail.akis.at (618940112 octets).
Feb  1 17:17:56 srv4hai fetchmail[1651]: 1162 messages (1162 seen) for admin@bastelstube.wien at pop3.world4you.com (52206525 octets).
Feb  1 17:17:59 srv4hai common[19660]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:17:59 srv4hai common[19684]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:18:00 srv4hai common[19708]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  1 17:18:01 srv4hai CRON[19733]: (root) CMD (/etc/linetest/linetest.sh)
Feb  1 17:18:04 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738426684 - 'ping_SP_0'
Feb  1 17:18:04 srv4hai ping_SP[11893]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  1 17:18:29 srv4hai common[19773]: SHELLY common.sh inkludiert von ./bt_scan.sh
Feb  1 17:18:39 srv4hai bt_scan[19773]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Feb  1 17:18:39 srv4hai bt_scan[19773]: SHELLY - ping_SP.sh started for BTmac: '1' - IPv4: '192.168.12.62'
Feb  1 17:18:39 srv4hai common[19806]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  1 17:18:39 srv4hai ping_SP[19806]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Feb  1 17:18:39 srv4hai common[19827]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  1 17:18:40 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'11'=1738426720-1738426709
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY (ping_SP__bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  1 17:18:40 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'11'=1738426720-1738426709
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.TRY} and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY (ping_SP__bt_scan)-(1) what: 'coming' - persPresent: '(__/LIVE) (BT/__)' - anyPresent: 'true' - otherPresent:'true'
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_tvlampTOGGLE) - sceneONsmall(vorraumOn)
Feb  1 17:18:40 srv4hai check_presence[19827]: SHELLY (ping_SP__bt_scan)-(1)-coming: activate_scenario.shtml: vorraumOn
Feb  1 17:18:40 srv4hai activate_scenario[19940]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  1 17:18:40 srv4hai activate_scenario[19940]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished