activate_scenario.sh
Thu Feb 6 22:04:29 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/06/_0.log)

vorher: 22:04:29.881

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: 22:04:29.937
Dauer: 56 msec phName: 'phoneName:'1''


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

urls : 1
vorher : 22:04:29.857
nachher: 22:04:29.943
Dauer : 86 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/02/06//scene_vorraumOn_20250206_220429.txt'


syslog

Feb  6 22:01:00 srv4hai common[16170]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:01:00 srv4hai common[16195]: SHELLY common.sh inkludiert von ./bt_scan.sh
Feb  6 22:01:00 srv4hai common[16203]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:01:00 srv4hai common[16232]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:01:01 srv4hai CRON[16258]: (root) CMD (/etc/linetest/linetest.sh)
Feb  6 22:01:10 srv4hai bt_scan[16195]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Feb  6 22:01:10 srv4hai bt_scan[16195]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Feb  6 22:01:39 srv4hai common[16292]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:01:39 srv4hai common[16316]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:01:49 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738875709 - 'ping_SP_0'
Feb  6 22:01:49 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  6 22:02:00 srv4hai common[16358]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:02:00 srv4hai common[16382]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:02:00 srv4hai common[16406]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:02:01 srv4hai CRON[16434]: (root) CMD (/etc/linetest/linetest.sh)
Feb  6 22:02:36 srv4hai postfix/smtpd[16453]: connect from unknown[92.118.39.64]
Feb  6 22:02:39 srv4hai common[16456]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:02:39 srv4hai common[16480]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:02:41 srv4hai postfix/smtpd[16453]: warning: unknown[92.118.39.64]: SASL PLAIN authentication failed: 
Feb  6 22:02:41 srv4hai postfix/smtpd[16453]: disconnect from unknown[92.118.39.64] ehlo=1 auth=0/1 quit=1 commands=2/3
Feb  6 22:02:54 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=16511, secured, session=
Feb  6 22:02:54 srv4hai dovecot: imap(miglincit)<16511>: 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  6 22:03:00 srv4hai common[16516]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:03:00 srv4hai common[16540]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:03:00 srv4hai common[16564]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:03:01 srv4hai CRON[16589]: (root) CMD (/etc/linetest/linetest.sh)
Feb  6 22:03:02 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738875782 - 'ping_SP_0'
Feb  6 22:03:02 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  6 22:03:35 srv4hai postfix/smtpd[16453]: connect from unknown[45.144.212.200]
Feb  6 22:03:35 srv4hai postfix/smtpd[16453]: warning: unknown[45.144.212.200]: SASL LOGIN authentication failed: Invalid authentication mechanism
Feb  6 22:03:35 srv4hai postfix/smtpd[16453]: lost connection after AUTH from unknown[45.144.212.200]
Feb  6 22:03:35 srv4hai postfix/smtpd[16453]: disconnect from unknown[45.144.212.200] ehlo=1 auth=0/1 commands=1/2
Feb  6 22:03:39 srv4hai common[16617]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:03:39 srv4hai common[16641]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:03:58 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:03:58 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:03:58 srv4hai dhcpd[4995]: DHCPDISCOVER from f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:03:58 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:03:58 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:03:58 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.62
Feb  6 22:03:58 srv4hai dhcpd[4995]: execute_statement argv[2] = GreteS20
Feb  6 22:03:58 srv4hai common[16673]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:00 srv4hai common[16701]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:04:00 srv4hai common[16728]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:04:00 srv4hai common[16752]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  6 22:04:01 srv4hai CRON[16779]: (root) CMD (/etc/linetest/linetest.sh)
Feb  6 22:04:06 srv4hai postfix/smtpd[16453]: connect from unknown[45.144.212.200]
Feb  6 22:04:06 srv4hai postfix/smtpd[16453]: warning: unknown[45.144.212.200]: SASL LOGIN authentication failed: Invalid authentication mechanism
Feb  6 22:04:06 srv4hai postfix/smtpd[16453]: lost connection after AUTH from unknown[45.144.212.200]
Feb  6 22:04:06 srv4hai postfix/smtpd[16453]: disconnect from unknown[45.144.212.200] ehlo=1 auth=0/1 commands=1/2
Feb  6 22:04:09 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Feb  6 22:04:09 srv4hai common[16792]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  6 22:04:09 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:09 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:09 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:04:09 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:04:09 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:09 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.62
Feb  6 22:04:09 srv4hai dhcpd[4995]: execute_statement argv[2] = GreteS20
Feb  6 22:04:09 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  6 22:04:09 srv4hai common[16805]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:09 srv4hai ping_SP[16792]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  6 22:04:09 srv4hai postfix/smtpd[16453]: warning: hostname mail1.diogovalenti.com does not resolve to address 107.172.25.104: Name or service not known
Feb  6 22:04:09 srv4hai postfix/smtpd[16453]: connect from unknown[107.172.25.104]
Feb  6 22:04:13 srv4hai postfix/smtpd[16453]: warning: unknown[107.172.25.104]: SASL PLAIN authentication failed: 
Feb  6 22:04:13 srv4hai postfix/smtpd[16453]: disconnect from unknown[107.172.25.104] ehlo=1 auth=0/1 quit=1 commands=2/3
Feb  6 22:04:13 srv4hai postfix/smtpd[16453]: warning: hostname 192-227-138-165-host.colocrossing.com does not resolve to address 192.227.138.165: Name or service not known
Feb  6 22:04:13 srv4hai postfix/smtpd[16453]: connect from unknown[192.227.138.165]
Feb  6 22:04:15 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738875855 - 'ping_SP_0'
Feb  6 22:04:15 srv4hai ping_SP[32560]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb  6 22:04:18 srv4hai postfix/smtpd[16453]: warning: unknown[192.227.138.165]: SASL PLAIN authentication failed: 
Feb  6 22:04:18 srv4hai postfix/smtpd[16453]: disconnect from unknown[192.227.138.165] ehlo=1 auth=0/1 quit=1 commands=2/3
Feb  6 22:04:19 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Feb  6 22:04:19 srv4hai common[16852]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  6 22:04:19 srv4hai common[16860]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  6 22:04:19 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:19 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:19 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:19 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.62
Feb  6 22:04:19 srv4hai dhcpd[4995]: execute_statement argv[2] = GreteS20
Feb  6 22:04:19 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  6 22:04:19 srv4hai common[16872]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:19 srv4hai ping_SP[16852]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  6 22:04:19 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'149'=1738875859-1738875710
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  6 22:04:19 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'149'=1738875859-1738875710
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_pcOFF) - sceneONsmall(vorraumOn)
Feb  6 22:04:19 srv4hai check_presence[16860]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Feb  6 22:04:19 srv4hai activate_scenario[16995]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  6 22:04:19 srv4hai activate_scenario[16995]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'
Feb  6 22:04:19 srv4hai activate_scenario[16995]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'1'
Feb  6 22:04:19 srv4hai postfix/pickup[7764]: D323263463: uid=33 from=
Feb  6 22:04:19 srv4hai postfix/cleanup[17046]: D323263463: message-id=<20250206210419.D323263463@mail.viehai.at>
Feb  6 22:04:19 srv4hai postfix/qmgr[8566]: D323263463: from=, size=10989, nrcpt=1 (queue active)
Feb  6 22:04:19 srv4hai activate_scenario[16995]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Feb  6 22:04:19 srv4hai proc_url[17063]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/06/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'1''
Feb  6 22:04:19 srv4hai activate_scenario[16995]: SHELLY scenario: 'vorraumOn(vorraumon)' took 99 msec, phoneName:'1'
Feb  6 22:04:20 srv4hai ping_SP[16792]: SHELLY check_presence.sh coming 1 ping_SP__smartphone
Feb  6 22:04:20 srv4hai postfix/relay/smtp[17059]: D323263463: to=, relay=mx03.nic.name[209.131.161.54]:25, delay=1.1, delays=0.05/0.01/0.73/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as AA47D60293)
Feb  6 22:04:20 srv4hai postfix/qmgr[8566]: D323263463: removed
Feb  6 22:04:22 srv4hai ping_SP[16792]: SHELLY - running process-id(16792): '16792 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#01216852 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.62 smartphone#01216872 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.62 GreteS20' - 'ping_SP_1' - exiting ==============
Feb  6 22:04:29 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.62' / 'GreteS20' / '1'
Feb  6 22:04:29 srv4hai common[17105]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  6 22:04:29 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.62 (192.168.12.252) from f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:29 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.62 to f0:39:65:25:8d:c7 via enp2s0
Feb  6 22:04:29 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:04:29 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: GreteS20.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: signer "rndc-key" approved
Feb  6 22:04:29 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:29 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.62
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' TXT
Feb  6 22:04:29 srv4hai dhcpd[4995]: execute_statement argv[2] = GreteS20
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' TXT "318a9f974c0d8a2868f64397b15c3ed3a0"
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'GreteS20.viehai.at' A
Feb  6 22:04:29 srv4hai named[30406]: client @0x7f931002f1e0 192.168.12.252#39411/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'GreteS20.viehai.at' A 192.168.12.62
Feb  6 22:04:29 srv4hai common[17122]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  6 22:04:29 srv4hai common[17125]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  6 22:04:29 srv4hai ping_SP[17105]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  6 22:04:29 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'159'=1738875869-1738875710
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  6 22:04:29 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'159'=1738875869-1738875710
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (ping_SP__smartphone)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (ping_SP__smartphone)-(1) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Feb  6 22:04:29 srv4hai check_presence[17122]: SHELLY (ping_SP__smartphone)-(1)-coming: activate_scenario.shtml: vorraumOn
Feb  6 22:04:29 srv4hai activate_scenario[17251]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  6 22:04:29 srv4hai activate_scenario[17251]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'1'

finished