activate_scenario.sh
Wed Jan 29 22:25:28 CET 2025
scenario: vorraumOn
phoneName: 0

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

VZ Licht (/srv/www/viehai/shelly/log_scenarios/2025/01/29/_0.log)

vorher: 22:25:28.885

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

nachher: 22:25:28.924
Dauer: 39 msec phName: 'phoneName:'0''

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

urls : 1
vorher : 22:25:28.858
nachher: 22:25:28.931
Dauer : 73 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/01/29//scene_vorraumOn_20250129_222528.txt'

syslog

Jan 29 22:22:01 srv4hai CRON[32642]: (root) CMD (/etc/linetest/linetest.sh)
Jan 29 22:22:14 srv4hai postfix/smtpd[32462]: connect from unknown[193.32.162.97]
Jan 29 22:22:18 srv4hai postfix/smtpd[32462]: warning: unknown[193.32.162.97]: SASL PLAIN authentication failed: 
Jan 29 22:22:18 srv4hai postfix/smtpd[32462]: disconnect from unknown[193.32.162.97] ehlo=1 auth=0/1 quit=1 commands=2/3
Jan 29 22:22:30 srv4hai dhcpd[4995]: DHCPDISCOVER from c8:db:26:0c:f3:8e via enp2s0
Jan 29 22:22:30 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Jan 29 22:22:30 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Jan 29 22:22:30 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.28
Jan 29 22:22:30 srv4hai dhcpd[4995]: execute_statement argv[2] = harmonyhub
Jan 29 22:22:30 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.28 (192.168.12.252) from c8:db:26:0c:f3:8e via enp2s0
Jan 29 22:22:30 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f93506682d0 192.168.12.252#33235/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f931c0a07f0 192.168.12.252#54671/key rndc-key: signer "rndc-key" approved
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f931c0a07f0 192.168.12.252#54671/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f931c0a07f0 192.168.12.252#54671/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713"
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f931c0a07f0 192.168.12.252#54671/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f931c0a07f0 192.168.12.252#54671/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28
Jan 29 22:22:30 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub'
Jan 29 22:22:30 srv4hai dhcpd[4995]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#33527/key rndc-key: signer "rndc-key" approved
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#33527/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR
Jan 29 22:22:30 srv4hai named[30406]: client @0x7f93100b4de0 192.168.12.252#33527/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 29 22:22:30 srv4hai dhcpd[4995]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at
Jan 29 22:22:30 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted
Jan 29 22:22:56 srv4hai common[32684]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:22:56 srv4hai common[32708]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:22:59 srv4hai common[32737]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:22:59 srv4hai common[32761]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:22:59 srv4hai common[320]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:23:01 srv4hai CRON[346]: (root) CMD (/etc/linetest/linetest.sh)
Jan 29 22:23:03 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=354, secured, session=
Jan 29 22:23:03 srv4hai dovecot: imap(miglincit)<354>: 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 29 22:23:03 srv4hai ping_SP[9165]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1738185783 - 'ping_SP_1'
Jan 29 22:23:03 srv4hai ping_SP[9165]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 29 22:23:06 srv4hai common[373]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jan 29 22:23:07 srv4hai ping_SP[14218]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738185787 - 'ping_SP_0'
Jan 29 22:23:07 srv4hai ping_SP[14218]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 29 22:23:10 srv4hai postfix/smtpd[32462]: warning: hostname 23-94-59-160-host.colocrossing.com does not resolve to address 23.94.59.160: Name or service not known
Jan 29 22:23:10 srv4hai postfix/smtpd[32462]: connect from unknown[23.94.59.160]
Jan 29 22:23:14 srv4hai postfix/smtpd[32462]: warning: unknown[23.94.59.160]: SASL PLAIN authentication failed: 
Jan 29 22:23:14 srv4hai postfix/smtpd[32462]: disconnect from unknown[23.94.59.160] ehlo=1 auth=0/1 quit=1 commands=2/3
Jan 29 22:23:16 srv4hai bt_scan[373]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Jan 29 22:23:16 srv4hai bt_scan[373]: SHELLY - ping_SP.sh already running for BTmac: '1' - IPv4: '192.168.12.62'
Jan 29 22:23:56 srv4hai common[441]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:23:56 srv4hai common[467]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:23:59 srv4hai common[494]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:23:59 srv4hai common[518]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:24:00 srv4hai common[543]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:24:01 srv4hai CRON[568]: (root) CMD (/etc/linetest/linetest.sh)
Jan 29 22:24:17 srv4hai ping_SP[9165]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1738185857 - 'ping_SP_1'
Jan 29 22:24:17 srv4hai ping_SP[9165]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 29 22:24:20 srv4hai ping_SP[14218]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1738185860 - 'ping_SP_0'
Jan 29 22:24:20 srv4hai ping_SP[14218]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 29 22:24:56 srv4hai common[627]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:24:56 srv4hai common[651]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:24:57 srv4hai dhcpd[4995]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:24:57 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:24:57 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:24:57 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Jan 29 22:24:57 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Jan 29 22:24:57 srv4hai common[675]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:24:59 srv4hai common[688]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:25:00 srv4hai common[712]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:25:00 srv4hai common[736]: SHELLY common.sh inkludiert von ./mqtt_process.sh
Jan 29 22:25:01 srv4hai CRON[762]: (root) CMD (/etc/linetest/linetest.sh)
Jan 29 22:25:08 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Jan 29 22:25:08 srv4hai common[771]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jan 29 22:25:08 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:08 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:08 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:08 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Jan 29 22:25:08 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Jan 29 22:25:08 srv4hai common[784]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:08 srv4hai ping_SP[771]: SHELLY ping_SP.sh started from smartphone, user:'root'
Jan 29 22:25:18 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Jan 29 22:25:18 srv4hai common[823]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jan 29 22:25:18 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:18 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:18 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:18 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Jan 29 22:25:18 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Jan 29 22:25:18 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#55515/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 29 22:25:18 srv4hai common[843]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jan 29 22:25:18 srv4hai common[847]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:18 srv4hai ping_SP[823]: SHELLY ping_SP.sh started from smartphone, user:'root'
Jan 29 22:25:19 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'12'=1738185919-1738185907
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Jan 29 22:25:19 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'12'=1738185919-1738185907
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (BT/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_nasszeile_um) - sceneONsmall(vorraumOn)
Jan 29 22:25:19 srv4hai check_presence[843]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Jan 29 22:25:19 srv4hai activate_scenario[993]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jan 29 22:25:19 srv4hai activate_scenario[993]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Jan 29 22:25:19 srv4hai activate_scenario[993]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Jan 29 22:25:19 srv4hai postfix/pickup[31310]: 8CA0263463: uid=33 from=
Jan 29 22:25:19 srv4hai postfix/cleanup[1045]: 8CA0263463: message-id=<20250129212519.8CA0263463@mail.viehai.at>
Jan 29 22:25:19 srv4hai postfix/qmgr[8566]: 8CA0263463: from=, size=11455, nrcpt=1 (queue active)
Jan 29 22:25:19 srv4hai activate_scenario[993]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Jan 29 22:25:19 srv4hai proc_url[1062]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2025/01/29/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Jan 29 22:25:19 srv4hai activate_scenario[993]: SHELLY scenario: 'vorraumOn(vorraumon)' took 78 msec, phoneName:'0'
Jan 29 22:25:19 srv4hai ping_SP[771]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Jan 29 22:25:21 srv4hai postfix/relay/smtp[1058]: 8CA0263463: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=2.1, delays=0.02/0.01/1.7/0.35, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 5D9008007E)
Jan 29 22:25:21 srv4hai postfix/qmgr[8566]: 8CA0263463: removed
Jan 29 22:25:21 srv4hai ping_SP[771]: SHELLY - running process-id(771): '771 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#012823 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#012847 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas#0121096 ping -c 4 192.168.12.61#01214218 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 bt_scan' - 'ping_SP_0' - exiting ==============
Jan 29 22:25:28 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Jan 29 22:25:28 srv4hai common[1113]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Jan 29 22:25:28 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:28 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Jan 29 22:25:28 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:28 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Jan 29 22:25:28 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Jan 29 22:25:28 srv4hai named[30406]: client @0x7f932c097490 192.168.12.252#45129/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 29 22:25:28 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#55515/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 29 22:25:28 srv4hai common[1130]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jan 29 22:25:28 srv4hai common[1133]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Jan 29 22:25:28 srv4hai ping_SP[1113]: SHELLY ping_SP.sh started from smartphone, user:'root'
Jan 29 22:25:28 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'8'=1738185928-1738185920
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Jan 29 22:25:28 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'8'=1738185928-1738185920
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (BT/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn)
Jan 29 22:25:28 srv4hai check_presence[1130]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Jan 29 22:25:28 srv4hai activate_scenario[1268]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Jan 29 22:25:28 srv4hai activate_scenario[1268]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished