activate_scenario.sh
Sat Feb 8 17:19:17 CET 2025
scenario: buero_UM
source: 192.168.12.140

'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'

scenario: 'Büro UM-schalten' - 'buero_UM' - 'buero_um'

TB Regallicht (/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log)

vorher: 17:19:17.255

http://192.168.12.108/relay/0?turn=toggle
  "ison": false,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 17:19:17.311
Dauer: 56 msec phName: 'shelly-40:'TB Licht''


TB Licht (/srv/www/viehai/shelly/log_scenarios/2025/02/08/_1.log)

vorher: 17:19:17.274

http://192.168.12.140/relay/0?turn=toggle
  "ison": false,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 17:19:17.339
Dauer: 65 msec phName: 'shelly-40:'TB Licht''


scenario: 'Büro UM-schalten' - 'buero_UM' - 'buero_um'

urls : 2
vorher : 17:19:17.231
nachher: 17:19:17.345
Dauer : 115 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/02/08//scene_buero_UM_20250208_171917.txt'


syslog

Feb  8 17:16:00 srv4hai common[5400]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:16:00 srv4hai common[5424]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:16:00 srv4hai common[5448]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:16:01 srv4hai CRON[5474]: (root) CMD (/etc/linetest/linetest.sh)
Feb  8 17:16:05 srv4hai ping_SP[4037]: SHELLY (0)- ping 192.168.12.61 4 not answered (1) @~1739031365 - 'ping_SP_0'
Feb  8 17:16:13 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5496, secured, session=<4R8+y6Mt1tkAAAAAAAAAAAAAAAAAAAAB>
Feb  8 17:16:13 srv4hai dovecot: imap(rozumm)<5496><4R8+y6Mt1tkAAAAAAAAAAAAAAAAAAAAB>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  8 17:16:28 srv4hai ping_SP[4037]: SHELLY (0)- ping 192.168.12.61 5 not answered (1) @~1739031388 - 'ping_SP_0'
Feb  8 17:16:28 srv4hai ping_SP[4037]: SHELLY (0)- ping 192.168.12.61 5 not answered - abort (4037) @~1739031388 - rm 'ping_SP_0'
Feb  8 17:16:28 srv4hai common[5519]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  8 17:16:29 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'26003'=1739031389-1739005386
Feb  8 17:16:29 srv4hai check_presence[5519]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  8 17:16:29 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'26003'=1739031389-1739005386
Feb  8 17:16:29 srv4hai check_presence[5519]: 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  8 17:16:29 srv4hai check_presence[5519]: SHELLY (ping_SP__NO_ping)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'presence'
Feb  8 17:16:29 srv4hai check_presence[5519]: SHELLY (ping_SP__NO_ping)-(0) what: 'presence' - persPresent: '(__/__) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  8 17:16:29 srv4hai check_presence[5519]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_vorraumOn) - sceneOFF(offAll)
Feb  8 17:16:29 srv4hai check_presence[5519]: SHELLY (ping_SP__NO_ping)-(0)-presence: NOT activate_scenario.shtml: offAll
Feb  8 17:16:29 srv4hai ping_SP[4037]: SHELLY (0)- TERMINATING now
Feb  8 17:16:35 srv4hai common[5633]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:16:35 srv4hai common[5657]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:16:45 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739031405 - 'ping_SP_1'
Feb  8 17:16:45 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb  8 17:16:59 srv4hai common[5699]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:17:00 srv4hai common[5723]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:17:00 srv4hai common[5747]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:17:01 srv4hai CRON[5776]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Feb  8 17:17:01 srv4hai CRON[5777]: (root) CMD (/etc/linetest/linetest.sh)
Feb  8 17:17:13 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5788, secured, session=
Feb  8 17:17:13 srv4hai dovecot: imap(rozumm)<5788>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  8 17:17:35 srv4hai common[5798]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:17:35 srv4hai common[5822]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:17:54 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5863, secured, session=<5zRG0aMt5NkAAAAAAAAAAAAAAAAAAAAB>
Feb  8 17:17:54 srv4hai dovecot: imap(miglincit)<5863><5zRG0aMt5NkAAAAAAAAAAAAAAAAAAAAB>: Logged out in=1199 out=1389 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  8 17:17:58 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739031478 - 'ping_SP_1'
Feb  8 17:17:58 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb  8 17:18:00 srv4hai common[5887]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:00 srv4hai common[5915]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:00 srv4hai common[5940]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:01 srv4hai CRON[5965]: (root) CMD (/etc/linetest/linetest.sh)
Feb  8 17:18:05 srv4hai mosquitto[29419]: Client shelly1-98CDAC2E1CDA has exceeded timeout, disconnecting.
Feb  8 17:18:05 srv4hai mosquitto[29419]: Socket error on client shelly1-98CDAC2E1CDA, disconnecting.
Feb  8 17:18:10 srv4hai postfix/anvil[4911]: statistics: max connection rate 2/60s for (smtp:45.144.212.200) at Feb  8 17:14:50
Feb  8 17:18:10 srv4hai postfix/anvil[4911]: statistics: max connection count 1 for (smtp:45.144.212.200) at Feb  8 17:14:17
Feb  8 17:18:10 srv4hai postfix/anvil[4911]: statistics: max cache size 1 at Feb  8 17:14:17
Feb  8 17:18:13 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5984, secured, session=
Feb  8 17:18:13 srv4hai dovecot: imap(rozumm)<5984>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  8 17:18:21 srv4hai dhcpd[4995]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:21 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:21 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:21 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Feb  8 17:18:21 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Feb  8 17:18:21 srv4hai common[5992]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:31 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb  8 17:18:31 srv4hai common[6016]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  8 17:18:31 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:31 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:31 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:31 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Feb  8 17:18:31 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Feb  8 17:18:31 srv4hai common[6029]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:31 srv4hai ping_SP[6016]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  8 17:18:35 srv4hai common[6052]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:35 srv4hai common[6076]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:38 srv4hai postfix/smtpd[6103]: connect from unknown[45.144.212.200]
Feb  8 17:18:38 srv4hai postfix/smtpd[6103]: warning: unknown[45.144.212.200]: SASL LOGIN authentication failed: Invalid authentication mechanism
Feb  8 17:18:38 srv4hai postfix/smtpd[6103]: lost connection after AUTH from unknown[45.144.212.200]
Feb  8 17:18:38 srv4hai postfix/smtpd[6103]: disconnect from unknown[45.144.212.200] ehlo=1 auth=0/1 commands=1/2
Feb  8 17:18:41 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb  8 17:18:41 srv4hai common[6118]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  8 17:18:41 srv4hai common[6127]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  8 17:18:41 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:41 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:41 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:41 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Feb  8 17:18:41 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Feb  8 17:18:41 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  8 17:18:41 srv4hai common[6138]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:41 srv4hai ping_SP[6118]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  8 17:18:41 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'26135'=1739031521-1739005386
Feb  8 17:18:41 srv4hai check_presence[6127]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  8 17:18:41 srv4hai check_presence[6127]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  8 17:18:41 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'26135'=1739031521-1739005386
Feb  8 17:18:41 srv4hai check_presence[6127]: 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  8 17:18:41 srv4hai check_presence[6127]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  8 17:18:41 srv4hai check_presence[6127]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  8 17:18:42 srv4hai check_presence[6127]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Feb  8 17:18:42 srv4hai check_presence[6127]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Feb  8 17:18:42 srv4hai activate_scenario[6263]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  8 17:18:42 srv4hai activate_scenario[6263]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Feb  8 17:18:42 srv4hai activate_scenario[6263]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Feb  8 17:18:42 srv4hai postfix/pickup[17410]: 121A863463: uid=33 from=
Feb  8 17:18:42 srv4hai postfix/cleanup[6314]: 121A863463: message-id=<20250208161842.121A863463@mail.viehai.at>
Feb  8 17:18:42 srv4hai postfix/qmgr[8566]: 121A863463: from=, size=12709, nrcpt=1 (queue active)
Feb  8 17:18:42 srv4hai activate_scenario[6263]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Feb  8 17:18:42 srv4hai proc_url[6331]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Feb  8 17:18:42 srv4hai activate_scenario[6263]: SHELLY scenario: 'vorraumOn(vorraumon)' took 60 msec, phoneName:'0'
Feb  8 17:18:42 srv4hai ping_SP[6016]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Feb  8 17:18:42 srv4hai postfix/relay/smtp[6327]: 121A863463: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=0.77, delays=0.02/0.01/0.48/0.26, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 9D5D880036)
Feb  8 17:18:42 srv4hai postfix/qmgr[8566]: 121A863463: removed
Feb  8 17:18:44 srv4hai ping_SP[6016]: SHELLY - running process-id(6016): '6016 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0126118 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0126138 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas' - 'ping_SP_0' - exiting ==============
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY scenario: 'buero_UM(buero_um)' started
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-40:'TB Licht'
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY scenario: 'buero_UM(buero_um)' TO BE EXECUTED, shelly-40:'TB Licht'
Feb  8 17:18:50 srv4hai postfix/pickup[17410]: 55C8963463: uid=33 from=
Feb  8 17:18:50 srv4hai postfix/cleanup[6314]: 55C8963463: message-id=<20250208161850.55C8963463@mail.viehai.at>
Feb  8 17:18:50 srv4hai postfix/qmgr[8566]: 55C8963463: from=, size=14587, nrcpt=1 (queue active)
Feb  8 17:18:50 srv4hai postfix/local[6428]: 55C8963463: to=, orig_to=, relay=local, delay=0.02, delays=0.01/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Feb  8 17:18:50 srv4hai postfix/cleanup[6314]: 5A5F763464: message-id=<20250208161850.5A5F763464@mail.viehai.at>
Feb  8 17:18:50 srv4hai postfix/bounce[6434]: 55C8963463: sender non-delivery notification: 5A5F763464
Feb  8 17:18:50 srv4hai postfix/qmgr[8566]: 5A5F763464: from=<>, size=16466, nrcpt=1 (queue active)
Feb  8 17:18:50 srv4hai postfix/qmgr[8566]: 55C8963463: removed
Feb  8 17:18:50 srv4hai postfix/local[6428]: 5A5F763464: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Feb  8 17:18:50 srv4hai postfix/qmgr[8566]: 5A5F763464: removed
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY: scenario: 'buero_UM(buero_um)'-'shelly-08'-'TB Regallicht' --> '0?turn=toggle'
Feb  8 17:18:50 srv4hai proc_url[6442]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log' - shellyUsage:'TB Regallicht' - scUrl:'http://192.168.12.108/relay/0?turn=toggle' - phName:'shelly-40:'TB Licht''
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY: scenario: 'buero_UM(buero_um)'-'shelly-40'-'TB Licht' --> '0?turn=toggle'
Feb  8 17:18:50 srv4hai proc_url[6453]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_1.log' - shellyUsage:'TB Licht' - scUrl:'http://192.168.12.140/relay/0?turn=toggle' - phName:'shelly-40:'TB Licht''
Feb  8 17:18:50 srv4hai activate_scenario[6371]: SHELLY scenario: 'buero_UM(buero_um)' took 111 msec, shelly-40:'TB Licht'
Feb  8 17:18:51 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb  8 17:18:51 srv4hai common[6500]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  8 17:18:51 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:51 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:18:51 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:51 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Feb  8 17:18:51 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Feb  8 17:18:51 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  8 17:18:51 srv4hai named[30406]: client @0x7f932c0c9f60 192.168.12.252#42223/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  8 17:18:51 srv4hai common[6512]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  8 17:18:51 srv4hai common[6520]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Feb  8 17:18:51 srv4hai ping_SP[6500]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  8 17:18:51 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'26145'=1739031531-1739005386
Feb  8 17:18:51 srv4hai check_presence[6512]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  8 17:18:51 srv4hai check_presence[6512]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  8 17:18:51 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'26145'=1739031531-1739005386
Feb  8 17:18:51 srv4hai check_presence[6512]: 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  8 17:18:51 srv4hai check_presence[6512]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  8 17:18:51 srv4hai check_presence[6512]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  8 17:18:52 srv4hai check_presence[6512]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_buero_UM) - sceneONsmall(vorraumOn)
Feb  8 17:18:52 srv4hai check_presence[6512]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Feb  8 17:18:52 srv4hai activate_scenario[6646]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  8 17:18:52 srv4hai activate_scenario[6646]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Feb  8 17:18:52 srv4hai activate_scenario[6646]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Feb  8 17:18:52 srv4hai postfix/pickup[17410]: 19C5463463: uid=33 from=
Feb  8 17:18:52 srv4hai postfix/cleanup[6314]: 19C5463463: message-id=<20250208161852.19C5463463@mail.viehai.at>
Feb  8 17:18:52 srv4hai postfix/qmgr[8566]: 19C5463463: from=, size=19731, nrcpt=1 (queue active)
Feb  8 17:18:52 srv4hai activate_scenario[6646]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Feb  8 17:18:52 srv4hai proc_url[6711]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Feb  8 17:18:52 srv4hai activate_scenario[6646]: SHELLY scenario: 'vorraumOn(vorraumon)' took 59 msec, phoneName:'0'
Feb  8 17:18:52 srv4hai ping_SP[6118]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Feb  8 17:18:53 srv4hai postfix/relay/smtp[6327]: 19C5463463: to=, relay=mx01.nic.name[209.131.159.47]:25, delay=1.1, delays=0.02/0/0.56/0.48, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as B6BDB60071)
Feb  8 17:18:53 srv4hai postfix/qmgr[8566]: 19C5463463: removed
Feb  8 17:18:54 srv4hai ping_SP[6118]: SHELLY - running process-id(6118): '6118 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0126500 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0126520 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas' - 'ping_SP_0' - exiting ==============
Feb  8 17:18:59 srv4hai common[6757]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:18:59 srv4hai common[6781]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:19:00 srv4hai common[6806]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Feb  8 17:19:01 srv4hai CRON[6833]: (root) CMD (/etc/linetest/linetest.sh)
Feb  8 17:19:01 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb  8 17:19:01 srv4hai common[6843]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Feb  8 17:19:01 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:19:01 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: signer "rndc-key" approved
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb  8 17:19:01 srv4hai named[30406]: client @0x7f93380d2cb0 192.168.12.252#45133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '61.12.168.192.in-addr.arpa' PTR fpThomas.viehai.at.
Feb  8 17:19:01 srv4hai common[6858]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  8 17:19:01 srv4hai ping_SP[6843]: SHELLY ping_SP.sh started from smartphone, user:'root'
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb  8 17:19:01 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb  8 17:19:01 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'26155'=1739031541-1739005386
Feb  8 17:19:01 srv4hai check_presence[6858]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  8 17:19:01 srv4hai check_presence[6858]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  8 17:19:01 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'26155'=1739031541-1739005386
Feb  8 17:19:01 srv4hai check_presence[6858]: 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  8 17:19:01 srv4hai check_presence[6858]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  8 17:19:01 srv4hai check_presence[6858]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  8 17:19:02 srv4hai check_presence[6858]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Feb  8 17:19:02 srv4hai check_presence[6858]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Feb  8 17:19:02 srv4hai activate_scenario[6981]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  8 17:19:02 srv4hai activate_scenario[6981]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Feb  8 17:19:02 srv4hai activate_scenario[6981]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Feb  8 17:19:02 srv4hai postfix/pickup[17410]: 2C29863463: uid=33 from=
Feb  8 17:19:02 srv4hai postfix/cleanup[6314]: 2C29863463: message-id=<20250208161902.2C29863463@mail.viehai.at>
Feb  8 17:19:02 srv4hai postfix/qmgr[8566]: 2C29863463: from=, size=28996, nrcpt=1 (queue active)
Feb  8 17:19:02 srv4hai activate_scenario[6981]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Feb  8 17:19:02 srv4hai proc_url[7046]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Feb  8 17:19:02 srv4hai activate_scenario[6981]: SHELLY scenario: 'vorraumOn(vorraumon)' took 189 msec, phoneName:'0'
Feb  8 17:19:02 srv4hai ping_SP[6500]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Feb  8 17:19:03 srv4hai postfix/relay/smtp[6327]: 2C29863463: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.96, delays=0.02/0/0.55/0.4, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as C7609600DC)
Feb  8 17:19:03 srv4hai postfix/qmgr[8566]: 2C29863463: removed
Feb  8 17:19:04 srv4hai ping_SP[6500]: SHELLY - running process-id(6500): '6500 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0126843 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone' - 'ping_SP_0' - exiting ==============
Feb  8 17:19:11 srv4hai postfix/smtpd[6103]: connect from unknown[45.144.212.200]
Feb  8 17:19:11 srv4hai postfix/smtpd[6103]: warning: unknown[45.144.212.200]: SASL LOGIN authentication failed: Invalid authentication mechanism
Feb  8 17:19:11 srv4hai postfix/smtpd[6103]: lost connection after AUTH from unknown[45.144.212.200]
Feb  8 17:19:11 srv4hai postfix/smtpd[6103]: disconnect from unknown[45.144.212.200] ehlo=1 auth=0/1 commands=1/2
Feb  8 17:19:11 srv4hai common[7100]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Feb  8 17:19:11 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1739031551 - 'ping_SP_1'
Feb  8 17:19:11 srv4hai ping_SP[10592]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Feb  8 17:19:11 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_older_60sec' dweetAge:'26165'=1739031551-1739005386
Feb  8 17:19:12 srv4hai check_presence[7100]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_older_60sec//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Feb  8 17:19:12 srv4hai check_presence[7100]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Feb  8 17:19:12 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_older_60sec' dweetAge:'26166'=1739031552-1739005386
Feb  8 17:19:12 srv4hai check_presence[7100]: 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  8 17:19:12 srv4hai check_presence[7100]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Feb  8 17:19:12 srv4hai check_presence[7100]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Feb  8 17:19:12 srv4hai check_presence[7100]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Feb  8 17:19:12 srv4hai check_presence[7100]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Feb  8 17:19:12 srv4hai activate_scenario[7222]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Feb  8 17:19:12 srv4hai activate_scenario[7222]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Feb  8 17:19:12 srv4hai activate_scenario[7222]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Feb  8 17:19:12 srv4hai postfix/pickup[17410]: 5320F63463: uid=33 from=
Feb  8 17:19:12 srv4hai postfix/cleanup[6314]: 5320F63463: message-id=<20250208161912.5320F63463@mail.viehai.at>
Feb  8 17:19:12 srv4hai postfix/qmgr[8566]: 5320F63463: from=, size=32879, nrcpt=1 (queue active)
Feb  8 17:19:12 srv4hai activate_scenario[7222]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Feb  8 17:19:12 srv4hai proc_url[7287]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/02/08/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Feb  8 17:19:12 srv4hai activate_scenario[7222]: SHELLY scenario: 'vorraumOn(vorraumon)' took 79 msec, phoneName:'0'
Feb  8 17:19:12 srv4hai ping_SP[6843]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Feb  8 17:19:13 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=7318, secured, session=
Feb  8 17:19:13 srv4hai postfix/relay/smtp[6327]: 5320F63463: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=0.74, delays=0.01/0/0.34/0.39, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as BB46B80085)
Feb  8 17:19:13 srv4hai postfix/qmgr[8566]: 5320F63463: removed
Feb  8 17:19:13 srv4hai dovecot: imap(rozumm)<7318>: Logged out in=92 out=1062 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Feb  8 17:19:14 srv4hai ping_SP[6843]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1739031554
Feb  8 17:19:17 srv4hai activate_scenario[7330]: SHELLY scenario: 'buero_UM(buero_um)' started
Feb  8 17:19:17 srv4hai activate_scenario[7330]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-40:'TB Licht'

finished