activate_scenario.sh
Mon May 27 06:46:36 CEST 2024
scenario: vorraumOn
phoneName: 0
vorher: 06:46:36.804
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: 06:46:36.844
Dauer: 40 msec
phName: 'phoneName:'0''
urls : 1
vorher : 06:46:36.786
nachher: 06:46:36.850
Dauer : 65 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/05/27//scene_vorraumOn_20240527_064636.txt'
May 27 06:43:01 srv4hai CRON[8130]: (root) CMD (/etc/linetest/linetest.sh) May 27 06:43:03 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8139, secured, session=<+UkVJ2gZMsUAAAAAAAAAAAAAAAAAAAAB> May 27 06:43:03 srv4hai dovecot: imap(miglincit)<8139><+UkVJ2gZMsUAAAAAAAAAAAAAAAAAAAAB>: Logged out in=226 out=1285 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0 May 27 06:43:05 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 1 sucessfully @~1716784985 - 'ping_SP_1' May 27 06:43:05 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 pCnt:'1' May 27 06:43:14 srv4hai postfix/smtp[8116]: connect to cdptwcgw01.timewarnercable.com[165.237.59.32]:25: Connection timed out May 27 06:43:27 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1716785007 - 'ping_SP_0' May 27 06:43:27 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 pCnt:'1' May 27 06:43:44 srv4hai postfix/smtpd[8189]: warning: database /etc/aliases.db is older than source file /etc/aliases May 27 06:43:44 srv4hai postfix/smtpd[8189]: connect from unknown[194.169.175.212] May 27 06:43:44 srv4hai postfix/smtp[8116]: connect to cdptwcgw02.timewarnercable.com[165.237.59.33]:25: Connection timed out May 27 06:43:44 srv4hai postfix/smtpd[8189]: warning: unknown[194.169.175.212]: SASL LOGIN authentication failed: Invalid authentication mechanism May 27 06:43:44 srv4hai postfix/smtpd[8189]: disconnect from unknown[194.169.175.212] ehlo=1 auth=0/1 quit=1 commands=2/3 May 27 06:43:52 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh May 27 06:43:52 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.28 May 27 06:43:52 srv4hai dhcpd[22856]: execute_statement argv[2] = harmonyhub May 27 06:43:52 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.28 from c8:db:26:0c:f3:8e via enp2s0 May 27 06:43:52 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.28 to c8:db:26:0c:f3:8e via enp2s0 May 27 06:43:52 srv4hai named[15509]: client @0x7f931c6a6030 192.168.12.252#52595/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: harmonyhub.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN) May 27 06:43:52 srv4hai named[15509]: client @0x7f93040c2620 192.168.12.252#56259/key rndc-key: signer "rndc-key" approved May 27 06:43:52 srv4hai named[15509]: client @0x7f93040c2620 192.168.12.252#56259/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' TXT May 27 06:43:52 srv4hai named[15509]: client @0x7f93040c2620 192.168.12.252#56259/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' TXT "3122b5a8b4281156e6f217b32b7e260713" May 27 06:43:52 srv4hai named[15509]: client @0x7f93040c2620 192.168.12.252#56259/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'harmonyhub.viehai.at' A May 27 06:43:52 srv4hai named[15509]: client @0x7f93040c2620 192.168.12.252#56259/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'harmonyhub.viehai.at' A 192.168.12.28 May 27 06:43:52 srv4hai dhcpd[22856]: Added new forward map from harmonyhub.viehai.at to 192.168.12.28 May 27 06:43:52 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.28'(28) device 'harmonyhub' May 27 06:43:52 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#45681/key rndc-key: signer "rndc-key" approved May 27 06:43:52 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#45681/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '28.12.168.192.in-addr.arpa' PTR May 27 06:43:52 srv4hai named[15509]: client @0x7f931c6b5500 192.168.12.252#45681/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. May 27 06:43:52 srv4hai dhcpd[22856]: Added reverse map from 28.12.168.192.in-addr.arpa. to harmonyhub.viehai.at May 27 06:43:52 srv4hai dhcpcommit.sh: SHELLY device 'harmonyhub' known and accepted May 27 06:44:01 srv4hai CRON[8209]: (root) CMD (/etc/linetest/linetest.sh) May 27 06:44:02 srv4hai common[8214]: SHELLY common.sh inkludiert von ./bt_scan.sh May 27 06:44:05 srv4hai named[15509]: resolver priming query complete May 27 06:44:06 srv4hai named[15509]: resolver priming query complete May 27 06:44:11 srv4hai named[15509]: resolver priming query complete May 27 06:44:12 srv4hai bt_scan[8214]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No' May 27 06:44:12 srv4hai bt_scan[8214]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence May 27 06:44:12 srv4hai common[8256]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh May 27 06:44:12 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'16'=1716785052-1716785036 May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence' May 27 06:44:12 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'16'=1716785052-1716785036 May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'presence' May 27 06:44:12 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'16'=1716785052-1716785036 May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true' May 27 06:44:12 srv4hai check_presence[8256]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_offAll) - sceneOFF(offAll) May 27 06:44:12 srv4hai check_presence[8256]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll May 27 06:44:14 srv4hai postfix/smtp[8116]: connect to cdctwcgw01.timewarnercable.com[165.237.91.113]:25: Connection timed out May 27 06:44:18 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 1 sucessfully @~1716785058 - 'ping_SP_1' May 27 06:44:18 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 pCnt:'1' May 27 06:44:40 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1716785080 - 'ping_SP_0' May 27 06:44:40 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 pCnt:'1' May 27 06:44:44 srv4hai postfix/smtp[8116]: connect to cdctwcgw02.timewarnercable.com[165.237.91.114]:25: Connection timed out May 27 06:44:44 srv4hai postfix/smtp[8116]: 9535C631F3: to= , relay=none, delay=384958, delays=384838/0.01/120/0, dsn=4.4.1, status=deferred (connect to cdctwcgw02.timewarnercable.com[165.237.91.114]:25: Connection timed out) May 27 06:45:01 srv4hai CRON[8458]: (root) CMD (/etc/linetest/linetest.sh) May 27 06:45:04 srv4hai named[15509]: resolver priming query complete May 27 06:45:31 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 1 sucessfully @~1716785131 - 'ping_SP_1' May 27 06:45:31 srv4hai ping_SP[26300]: SHELLY (1) - ping 192.168.12.62 pCnt:'1' May 27 06:45:39 srv4hai named[15509]: resolver priming query complete May 27 06:45:53 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1716785153 - 'ping_SP_0' May 27 06:45:53 srv4hai ping_SP[8008]: SHELLY (0) - ping 192.168.12.61 pCnt:'1' May 27 06:46:01 srv4hai CRON[8529]: (root) CMD (/etc/linetest/linetest.sh) May 27 06:46:03 srv4hai dovecot: imap-login: Login: user= , method=PLAIN, rip=::1, lip=::1, mpid=8536, secured, session= May 27 06:46:03 srv4hai dovecot: imap(miglincit)<8536> : Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0 May 27 06:46:05 srv4hai dhcpd[22856]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:05 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:05 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh May 27 06:46:05 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61 May 27 06:46:05 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas May 27 06:46:05 srv4hai common[8539]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh May 27 06:46:15 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0' May 27 06:46:15 srv4hai common[8555]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh May 27 06:46:15 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:15 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:15 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh May 27 06:46:15 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61 May 27 06:46:15 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas May 27 06:46:15 srv4hai common[8568]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh May 27 06:46:15 srv4hai ping_SP[8555]: SHELLY ping_SP.sh started from smartphone, user:'root' May 27 06:46:26 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0' May 27 06:46:26 srv4hai common[8599]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh May 27 06:46:26 srv4hai common[8608]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh May 27 06:46:26 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:26 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:26 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh May 27 06:46:26 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61 May 27 06:46:26 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas May 27 06:46:26 srv4hai named[15509]: client @0x7f931810e280 192.168.12.252#42951/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN) May 27 06:46:26 srv4hai common[8620]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh May 27 06:46:26 srv4hai ping_SP[8599]: SHELLY ping_SP.sh started from smartphone, user:'root' May 27 06:46:26 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'10'=1716785186-1716785176 May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming' May 27 06:46:26 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'10'=1716785186-1716785176 May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming' May 27 06:46:26 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'10'=1716785186-1716785176 May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true' May 27 06:46:26 srv4hai check_presence[8608]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn) May 27 06:46:26 srv4hai check_presence[8608]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn May 27 06:46:26 srv4hai activate_scenario[8783]: SHELLY scenario: 'vorraumOn(vorraumon)' started May 27 06:46:26 srv4hai activate_scenario[8783]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0' May 27 06:46:26 srv4hai activate_scenario[8783]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0' May 27 06:46:26 srv4hai postfix/pickup[24818]: D574E631B9: uid=33 from= May 27 06:46:26 srv4hai postfix/cleanup[8833]: D574E631B9: message-id=<20240527044626.D574E631B9@mail.viehai.at> May 27 06:46:26 srv4hai postfix/qmgr[15291]: D574E631B9: from= , size=13396, nrcpt=1 (queue active) May 27 06:46:26 srv4hai activate_scenario[8783]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on' May 27 06:46:26 srv4hai proc_url[8850]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/05/27/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0'' May 27 06:46:26 srv4hai named[15509]: resolver priming query complete May 27 06:46:26 srv4hai activate_scenario[8783]: SHELLY scenario: 'vorraumOn(vorraumon)' took 53 msec, phoneName:'0' May 27 06:46:26 srv4hai ping_SP[8555]: SHELLY check_presence.sh coming 0 ping_SP__smartphone May 27 06:46:27 srv4hai named[15509]: resolver priming query complete May 27 06:46:27 srv4hai postfix/relay/smtp[8846]: D574E631B9: to= , relay=mx02.nic.name[209.131.158.56]:25, delay=1.1, delays=0.02/0.01/0.78/0.29, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as BB64E2000D) May 27 06:46:27 srv4hai postfix/qmgr[15291]: D574E631B9: removed May 27 06:46:29 srv4hai ping_SP[8555]: SHELLY - running process-id(8555): '8008 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 bt_scan#0128555 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0128599 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61 smartphone#0128620 /bin/bash /srv/www/viehai/shelly/smartphone.sh 192.168.12.61 fpThomas' - 'ping_SP_0' - exiting ============== May 27 06:46:36 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0' May 27 06:46:36 srv4hai common[8898]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh May 27 06:46:36 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:36 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0 May 27 06:46:36 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh May 27 06:46:36 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61 May 27 06:46:36 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas May 27 06:46:36 srv4hai named[15509]: client @0x7f931810e280 192.168.12.252#42951/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN) May 27 06:46:36 srv4hai named[15509]: client @0x7f93100ff750 192.168.12.252#50157/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN) May 27 06:46:36 srv4hai common[8911]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh May 27 06:46:36 srv4hai common[8919]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh May 27 06:46:36 srv4hai ping_SP[8898]: SHELLY ping_SP.sh started from smartphone, user:'root' May 27 06:46:36 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'2'=1716785196-1716785194 May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming' May 27 06:46:36 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'2'=1716785196-1716785194 May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming' May 27 06:46:36 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'2'=1716785196-1716785194 May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true' May 27 06:46:36 srv4hai check_presence[8911]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_vorraumOn) - sceneONsmall(vorraumOn) May 27 06:46:36 srv4hai check_presence[8911]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn May 27 06:46:36 srv4hai activate_scenario[9084]: SHELLY scenario: 'vorraumOn(vorraumon)' started May 27 06:46:36 srv4hai activate_scenario[9084]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
finished