activate_scenario.sh
Wed Sep 11 14:49:55 CEST 2024
scenario: vorraumOn
phoneName: 1

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/09/11/_0.log)

vorher: 14:49:55.298

  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "overpower": false,
  "source": "input"

nachher: 14:49:55.430
Dauer: 131 msec phName: 'phoneName:'1''

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

urls : 1
vorher : 14:49:55.279
nachher: 14:49:55.436
Dauer : 157 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/09/11//scene_vorraumOn_20240911_144955.txt'

syslog

Sep 11 14:46:00 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 11 14:46:00 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 11 14:46:00 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Sep 11 14:46:00 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Sep 11 14:46:00 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Sep 11 14:46:00 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Sep 11 14:46:00 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f93506b28d0 192.168.12.252#60421/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#45227/key rndc-key: signer "rndc-key" approved
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#45227/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#45227/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#45227/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f932c012aa0 192.168.12.252#45227/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Sep 11 14:46:00 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Sep 11 14:46:00 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#52583/key rndc-key: signer "rndc-key" approved
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#52583/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Sep 11 14:46:00 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#52583/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 11 14:46:00 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Sep 11 14:46:00 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Sep 11 14:46:01 srv4hai CRON[31898]: (root) CMD (/etc/linetest/linetest.sh)
Sep 11 14:46:56 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=31919, secured, session=
Sep 11 14:46:56 srv4hai dovecot: imap(rozumm)<31919>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Sep 11 14:47:00 srv4hai postfix/smtpd[31926]: connect from unknown[87.121.69.225]
Sep 11 14:47:00 srv4hai postfix/smtpd[31926]: warning: unknown[87.121.69.225]: SASL LOGIN authentication failed: Invalid authentication mechanism
Sep 11 14:47:00 srv4hai postfix/smtpd[31926]: lost connection after AUTH from unknown[87.121.69.225]
Sep 11 14:47:00 srv4hai postfix/smtpd[31926]: disconnect from unknown[87.121.69.225] ehlo=1 auth=0/1 commands=1/2
Sep 11 14:47:01 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726058821 - 'ping_SP_1'
Sep 11 14:47:01 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 11 14:47:01 srv4hai CRON[31943]: (root) CMD (/etc/linetest/linetest.sh)
Sep 11 14:47:56 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=31962, secured, session=
Sep 11 14:47:56 srv4hai dovecot: imap(rozumm)<31962>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Sep 11 14:48:01 srv4hai CRON[31969]: (root) CMD (/etc/linetest/linetest.sh)
Sep 11 14:48:03 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Sep 11 14:48:05 srv4hai fetchmail[1651]: 52080 messages (52080 seen) for tmiglinci at mail.akis.at (-7721843 octets).
Sep 11 14:48:05 srv4hai fetchmail[1651]: 10705 messages (10705 seen) for srozum at mail.akis.at (1292062541 octets).
Sep 11 14:48:06 srv4hai fetchmail[1651]: 25832 messages (25832 seen) for mrozum at mail.akis.at (201218531 octets).
Sep 11 14:48:06 srv4hai fetchmail[1651]: 1142 messages (1142 seen) for admin@bastelstube.wien at pop3.world4you.com (51614275 octets).
Sep 11 14:48:11 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=31979, secured, session=
Sep 11 14:48:11 srv4hai dovecot: imap(miglincit)<31979>: Logged out in=597 out=1232 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Sep 11 14:48:14 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726058894 - 'ping_SP_1'
Sep 11 14:48:14 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 11 14:48:56 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=32004, secured, session=<4tfBadchHsAAAAAAAAAAAAAAAAAAAAAB>
Sep 11 14:48:56 srv4hai dovecot: imap(rozumm)<32004><4tfBadchHsAAAAAAAAAAAAAAAAAAAAAB>: Logged out in=92 out=1060 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Sep 11 14:49:01 srv4hai postfix/smtpd[32000]: connect from unknown[80.94.92.146]
Sep 11 14:49:01 srv4hai CRON[32009]: (root) CMD (/etc/linetest/linetest.sh)
Sep 11 14:49:05 srv4hai postfix/smtpd[32000]: warning: unknown[80.94.92.146]: SASL PLAIN authentication failed: 
Sep 11 14:49:05 srv4hai postfix/smtpd[32000]: disconnect from unknown[80.94.92.146] ehlo=1 auth=0/1 quit=1 commands=2/3
Sep 11 14:49:24 srv4hai dhcpd[30731]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:24 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:24 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:24 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Sep 11 14:49:24 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Sep 11 14:49:24 srv4hai common[32023]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:28 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726058968 - 'ping_SP_1'
Sep 11 14:49:28 srv4hai ping_SP[27965]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 11 14:49:34 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Sep 11 14:49:34 srv4hai common[32052]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 11 14:49:34 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:34 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:34 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:34 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Sep 11 14:49:34 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Sep 11 14:49:34 srv4hai common[32065]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:34 srv4hai ping_SP[32052]: SHELLY ping_SP.sh started from smartphone, user:'root'
Sep 11 14:49:44 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Sep 11 14:49:44 srv4hai common[32094]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 11 14:49:44 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:44 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:44 srv4hai common[32106]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 11 14:49:44 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:44 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Sep 11 14:49:44 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Sep 11 14:49:44 srv4hai named[30406]: client @0x7f93506682d0 192.168.12.252#49159/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 11 14:49:44 srv4hai common[32115]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:44 srv4hai ping_SP[32094]: SHELLY ping_SP.sh started from smartphone, user:'root'
Sep 11 14:49:45 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'11'=1726058985-1726058974
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Sep 11 14:49:45 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'11'=1726058985-1726058974
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Sep 11 14:49:45 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'11'=1726058985-1726058974
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Sep 11 14:49:45 srv4hai check_presence[32106]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Sep 11 14:49:45 srv4hai activate_scenario[32278]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 11 14:49:45 srv4hai activate_scenario[32278]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'
Sep 11 14:49:45 srv4hai activate_scenario[32278]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'1'
Sep 11 14:49:45 srv4hai postfix/pickup[31544]: 536896345B: uid=33 from=
Sep 11 14:49:45 srv4hai postfix/cleanup[32328]: 536896345B: message-id=<20240911124945.536896345B@mail.viehai.at>
Sep 11 14:49:45 srv4hai activate_scenario[32278]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Sep 11 14:49:45 srv4hai postfix/qmgr[8566]: 536896345B: from=, size=11378, nrcpt=1 (queue active)
Sep 11 14:49:45 srv4hai proc_url[32344]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/11/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'1''
Sep 11 14:49:45 srv4hai activate_scenario[32278]: SHELLY scenario: 'vorraumOn(vorraumon)' took 108 msec, phoneName:'1'
Sep 11 14:49:45 srv4hai ping_SP[32052]: SHELLY check_presence.sh coming 1 ping_SP__smartphone
Sep 11 14:49:46 srv4hai postfix/relay/smtp[32345]: 536896345B: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.87, delays=0.08/0.01/0.51/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as F3B5B80032)
Sep 11 14:49:46 srv4hai postfix/qmgr[8566]: 536896345B: removed
Sep 11 14:49:47 srv4hai ping_SP[32052]: SHELLY - running process-id(32052): '27965 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 bt_scan#01232052 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#01232094 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#01232115 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.62 GreteS20' - 'ping_SP_1' - exiting ==============
Sep 11 14:49:51 srv4hai postfix/smtpd[32000]: connect from unknown[80.94.92.124]
Sep 11 14:49:54 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Sep 11 14:49:54 srv4hai common[32386]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 11 14:49:54 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:54 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Sep 11 14:49:54 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:54 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.62
Sep 11 14:49:54 srv4hai dhcpd[30731]: execute_statement argv[2] = GreteS20
Sep 11 14:49:54 srv4hai named[30406]: client @0x7f93100a5fe0 192.168.12.252#57243/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 11 14:49:54 srv4hai named[30406]: client @0x7f93506682d0 192.168.12.252#49159/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 11 14:49:54 srv4hai common[32403]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 11 14:49:54 srv4hai common[32407]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 11 14:49:54 srv4hai ping_SP[32386]: SHELLY ping_SP.sh started from smartphone, user:'root'
Sep 11 14:49:55 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'4'=1726058995-1726058991
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Sep 11 14:49:55 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'4'=1726058995-1726058991
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Sep 11 14:49:55 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'4'=1726058995-1726058991
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Sep 11 14:49:55 srv4hai postfix/smtpd[32000]: warning: unknown[80.94.92.124]: SASL PLAIN authentication failed: 
Sep 11 14:49:55 srv4hai postfix/smtpd[32000]: disconnect from unknown[80.94.92.124] ehlo=1 auth=0/1 quit=1 commands=2/3
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Sep 11 14:49:55 srv4hai check_presence[32403]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Sep 11 14:49:55 srv4hai activate_scenario[32573]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 11 14:49:55 srv4hai activate_scenario[32573]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished