activate_scenario.sh
Wed Dec 18 17:35:04 CET 2024
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/2024/12/18/_0.log)
vorher: 17:35:04.482
http://192.168.12.108/relay/0?turn=toggle |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"source": "http"
|
nachher: 17:35:04.614
Dauer: 132 msec
phName: 'shelly-40:'TB Licht''
TB Licht (/srv/www/viehai/shelly/log_scenarios/2024/12/18/_1.log)
vorher: 17:35:04.498
http://192.168.12.140/relay/0?turn=toggle |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"source": "http"
|
nachher: 17:35:04.581
Dauer: 83 msec
phName: 'shelly-40:'TB Licht''
scenario: 'Büro UM-schalten' - 'buero_UM' - 'buero_um'
urls : 2
vorher : 17:35:04.457
nachher: 17:35:04.621
Dauer : 165 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/18//scene_buero_UM_20241218_173504.txt'
syslog
Dec 18 17:32:00 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:00 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:00 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 18 17:32:00 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.5
Dec 18 17:32:00 srv4hai dhcpd[4995]: execute_statement argv[2] = btscan1
Dec 18 17:32:00 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:00 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f932c0d0080 192.168.12.252#50245/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#47467/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#47467/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#47467/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#47467/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f93100971e0 192.168.12.252#47467/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Dec 18 17:32:00 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Dec 18 17:32:00 srv4hai dhcpd[4995]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f9338096e40 192.168.12.252#56849/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f9338096e40 192.168.12.252#56849/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Dec 18 17:32:00 srv4hai named[30406]: client @0x7f9338096e40 192.168.12.252#56849/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.
Dec 18 17:32:00 srv4hai dhcpd[4995]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Dec 18 17:32:00 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Dec 18 17:32:01 srv4hai CRON[13714]: (root) CMD (/etc/linetest/linetest.sh)
Dec 18 17:32:08 srv4hai mosquitto[29419]: New connection from 192.168.12.101 on port 1883.
Dec 18 17:32:08 srv4hai mosquitto[29419]: Socket error on client , disconnecting.
Dec 18 17:32:55 srv4hai dhcpd[4995]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Dec 18 17:32:55 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 18 17:32:55 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Dec 18 17:32:55 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Dec 18 17:32:55 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Dec 18 17:32:55 srv4hai common[13731]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Dec 18 17:32:55 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Dec 18 17:32:55 srv4hai common[13746]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 18 17:32:55 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Dec 18 17:32:55 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#38563/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#34181/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#34181/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#34181/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#34181/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#34181/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Dec 18 17:32:55 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93501490e0 192.168.12.252#37961/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93501490e0 192.168.12.252#37961/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Dec 18 17:32:55 srv4hai named[30406]: client @0x7f93501490e0 192.168.12.252#37961/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.
Dec 18 17:32:55 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Dec 18 17:32:55 srv4hai ping_SP[13746]: SHELLY ping_SP.sh started from smartphone, user:'root'
Dec 18 17:32:56 srv4hai dhcpd[4995]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:56 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:56 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 18 17:32:56 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.5
Dec 18 17:32:56 srv4hai dhcpd[4995]: execute_statement argv[2] = btscan1
Dec 18 17:32:56 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:56 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f93300fcc20 192.168.12.252#57193/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f932c021730 192.168.12.252#38825/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f932c021730 192.168.12.252#38825/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f932c021730 192.168.12.252#38825/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f932c021730 192.168.12.252#38825/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f932c021730 192.168.12.252#38825/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Dec 18 17:32:56 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Dec 18 17:32:56 srv4hai dhcpd[4995]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f93480026a0 192.168.12.252#37257/key rndc-key: signer "rndc-key" approved
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f93480026a0 192.168.12.252#37257/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Dec 18 17:32:56 srv4hai named[30406]: client @0x7f93480026a0 192.168.12.252#37257/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.
Dec 18 17:32:56 srv4hai dhcpd[4995]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Dec 18 17:32:56 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Dec 18 17:33:01 srv4hai CRON[13775]: (root) CMD (/etc/linetest/linetest.sh)
Dec 18 17:33:01 srv4hai systemd[1]: Started Run anacron jobs.
Dec 18 17:33:01 srv4hai anacron[13777]: Anacron 2.3 started on 2024-12-18
Dec 18 17:33:01 srv4hai anacron[13777]: Normal exit (0 jobs run)
Dec 18 17:33:01 srv4hai systemd[1]: anacron.service: Succeeded.
Dec 18 17:33:03 srv4hai mosquitto[29419]: New connection from 192.168.12.101 on port 1883.
Dec 18 17:33:03 srv4hai mosquitto[29419]: Socket error on client , disconnecting.
Dec 18 17:33:05 srv4hai common[13783]: SHELLY common.sh inkludiert von ./bt_scan.sh
Dec 18 17:33:06 srv4hai common[13805]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 18 17:33:06 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'1'=1734539586-1734539585
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Dec 18 17:33:06 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'1'=1734539586-1734539585
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Dec 18 17:33:06 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'1'=1734539586-1734539585
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Dec 18 17:33:06 srv4hai check_presence[13805]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Dec 18 17:33:06 srv4hai activate_scenario[13937]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Dec 18 17:33:06 srv4hai activate_scenario[13937]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Dec 18 17:33:06 srv4hai activate_scenario[13937]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Dec 18 17:33:06 srv4hai postfix/pickup[998]: D164463463: uid=33 from=
Dec 18 17:33:06 srv4hai postfix/cleanup[13987]: D164463463: message-id=<20241218163306.D164463463@mail.viehai.at>
Dec 18 17:33:06 srv4hai postfix/qmgr[8566]: D164463463: from=, size=21683, nrcpt=1 (queue active)
Dec 18 17:33:06 srv4hai activate_scenario[13937]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'VZ Licht' --> '0?turn=on'
Dec 18 17:33:06 srv4hai proc_url[14004]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/12/18/_0.log' - shellyUsage:'VZ Licht' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Dec 18 17:33:07 srv4hai activate_scenario[13937]: SHELLY scenario: 'vorraumOn(vorraumon)' took 177 msec, phoneName:'0'
Dec 18 17:33:07 srv4hai ping_SP[13746]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Dec 18 17:33:07 srv4hai postfix/relay/smtp[14000]: D164463463: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=1, delays=0.05/0.01/0.6/0.37, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 86A0E602F9)
Dec 18 17:33:07 srv4hai postfix/qmgr[8566]: D164463463: removed
Dec 18 17:33:09 srv4hai ping_SP[13746]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1734539589
Dec 18 17:33:15 srv4hai bt_scan[13783]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Dec 18 17:33:15 srv4hai bt_scan[13783]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Dec 18 17:33:22 srv4hai ping_SP[13746]: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1734539602 - 'ping_SP_0'
Dec 18 17:33:22 srv4hai ping_SP[13746]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 18 17:34:00 srv4hai mosquitto[29419]: New connection from 192.168.12.101 on port 1883.
Dec 18 17:34:00 srv4hai mosquitto[29419]: Socket error on client , disconnecting.
Dec 18 17:34:01 srv4hai CRON[14097]: (root) CMD (/etc/linetest/linetest.sh)
Dec 18 17:34:35 srv4hai ping_SP[13746]: SHELLY (0) - ping 192.168.12.61 1 sucessfully @~1734539675 - 'ping_SP_0'
Dec 18 17:34:35 srv4hai ping_SP[13746]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 18 17:34:59 srv4hai mosquitto[29419]: New connection from 192.168.12.101 on port 1883.
Dec 18 17:34:59 srv4hai mosquitto[29419]: Socket error on client , disconnecting.
Dec 18 17:35:01 srv4hai CRON[14130]: (root) CMD (/etc/linetest/linetest.sh)
Dec 18 17:35:04 srv4hai activate_scenario[14136]: SHELLY scenario: 'buero_UM(buero_um)' started
Dec 18 17:35:04 srv4hai activate_scenario[14136]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-40:'TB Licht'
finished