activate_scenario.sh
Sun Sep 15 09:29:46 CEST 2024
scenario: buero_UM
source: 192.168.12.140

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

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

Thomas Büro Regallicht (/srv/www/viehai/shelly/log_scenarios/2024/09/15/_0.log)

vorher: 09:29:46.276

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: 09:29:46.309
Dauer: 32 msec phName: 'shelly-40:'Thomas Büro Licht''


Thomas Büro Licht (/srv/www/viehai/shelly/log_scenarios/2024/09/15/_1.log)

vorher: 09:29:46.288

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: 09:29:46.324
Dauer: 37 msec phName: 'shelly-40:'Thomas Büro Licht''


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

urls : 2
vorher : 09:29:46.257
nachher: 09:29:46.331
Dauer : 74 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/09/15//scene_buero_UM_20240915_092946.txt'


syslog

Sep 15 09:26:01 srv4hai CRON[13195]: (root) CMD (/etc/linetest/linetest.sh)
Sep 15 09:26:17 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726385177 - 'ping_SP_1'
Sep 15 09:26:17 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 15 09:26:33 srv4hai postfix/anvil[12815]: statistics: max connection rate 4/60s for (smtp:89.190.156.156) at Sep 15 09:23:13
Sep 15 09:26:33 srv4hai postfix/anvil[12815]: statistics: max connection count 1 for (smtp:89.190.156.156) at Sep 15 09:22:52
Sep 15 09:26:33 srv4hai postfix/anvil[12815]: statistics: max cache size 1 at Sep 15 09:22:52
Sep 15 09:26:45 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Sep 15 09:26:46 srv4hai fetchmail[1651]: 52341 messages (52341 seen) for tmiglinci at mail.akis.at (12544837 octets).
Sep 15 09:26:47 srv4hai fetchmail[1651]: 10723 messages (10723 seen) for srozum at mail.akis.at (1301892974 octets).
Sep 15 09:26:47 srv4hai fetchmail[1651]: 25926 messages (25926 seen) for mrozum at mail.akis.at (215397467 octets).
Sep 15 09:26:47 srv4hai fetchmail[1651]: 1143 messages (1143 seen) for admin@bastelstube.wien at pop3.world4you.com (51639660 octets).
Sep 15 09:26:50 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726385210 - 'ping_SP_0'
Sep 15 09:26:50 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 15 09:27:01 srv4hai CRON[13245]: (root) CMD (/etc/linetest/linetest.sh)
Sep 15 09:27:01 srv4hai nmbd[2172]: [2024/09/15 09:27:01.940746,  0] ../source3/nmbd/nmbd_browsesync.c:354(find_domain_master_name_query_fail)
Sep 15 09:27:01 srv4hai nmbd[2172]:   find_domain_master_name_query_fail:
Sep 15 09:27:01 srv4hai nmbd[2172]:   Unable to find the Domain Master Browser name WORKGROUP<1b> for the workgroup WORKGROUP.
Sep 15 09:27:01 srv4hai nmbd[2172]:   Unable to sync browse lists in this workgroup.
Sep 15 09:27:30 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726385250 - 'ping_SP_1'
Sep 15 09:27:30 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 15 09:27:43 srv4hai mosquitto[18551]: Client shelly1-98CDAC2E1CDA has exceeded timeout, disconnecting.
Sep 15 09:27:43 srv4hai mosquitto[18551]: Socket error on client shelly1-98CDAC2E1CDA, disconnecting.
Sep 15 09:27:47 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:47 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:51 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:51 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:51 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Sep 15 09:27:51 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Sep 15 09:27:51 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Sep 15 09:27:51 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:51 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f935013a950 192.168.12.252#53563/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#46223/key rndc-key: signer "rndc-key" approved
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#46223/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#46223/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#46223/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f931c0a2850 192.168.12.252#46223/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Sep 15 09:27:51 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Sep 15 09:27:51 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#49481/key rndc-key: signer "rndc-key" approved
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#49481/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Sep 15 09:27:51 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#49481/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.
Sep 15 09:27:51 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Sep 15 09:27:51 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Sep 15 09:28:01 srv4hai CRON[13286]: (root) CMD (/etc/linetest/linetest.sh)
Sep 15 09:28:03 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726385283 - 'ping_SP_0'
Sep 15 09:28:03 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY scenario: 'buero_UM(buero_um)' started
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-40:'Thomas Büro Licht'
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY scenario: 'buero_UM(buero_um)' TO BE EXECUTED, shelly-40:'Thomas Büro Licht'
Sep 15 09:28:33 srv4hai postfix/pickup[31615]: CC2576345B: uid=33 from=
Sep 15 09:28:33 srv4hai postfix/cleanup[13375]: CC2576345B: message-id=<20240915072833.CC2576345B@mail.viehai.at>
Sep 15 09:28:33 srv4hai postfix/qmgr[8566]: CC2576345B: from=, size=6878, nrcpt=1 (queue active)
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY: scenario: 'buero_UM(buero_um)'-'shelly-08'-'Thomas Büro Regallicht' --> '0?turn=toggle'
Sep 15 09:28:33 srv4hai proc_url[13393]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/15/_0.log' - shellyUsage:'Thomas Büro Regallicht' - scUrl:'http://192.168.12.108/relay/0?turn=toggle' - phName:'shelly-40:'Thomas Büro Licht''
Sep 15 09:28:33 srv4hai postfix/local[13388]: CC2576345B: to=, orig_to=, relay=local, delay=0.04, delays=0.02/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Sep 15 09:28:33 srv4hai postfix/cleanup[13375]: D18546345C: message-id=<20240915072833.D18546345C@mail.viehai.at>
Sep 15 09:28:33 srv4hai postfix/bounce[13389]: CC2576345B: sender non-delivery notification: D18546345C
Sep 15 09:28:33 srv4hai postfix/qmgr[8566]: D18546345C: from=<>, size=8800, nrcpt=1 (queue active)
Sep 15 09:28:33 srv4hai postfix/qmgr[8566]: CC2576345B: removed
Sep 15 09:28:33 srv4hai postfix/local[13388]: D18546345C: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Sep 15 09:28:33 srv4hai postfix/qmgr[8566]: D18546345C: removed
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY: scenario: 'buero_UM(buero_um)'-'shelly-40'-'Thomas Büro Licht' --> '0?turn=toggle'
Sep 15 09:28:33 srv4hai proc_url[13405]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/15/_1.log' - shellyUsage:'Thomas Büro Licht' - scUrl:'http://192.168.12.140/relay/0?turn=toggle' - phName:'shelly-40:'Thomas Büro Licht''
Sep 15 09:28:33 srv4hai activate_scenario[13319]: SHELLY scenario: 'buero_UM(buero_um)' took 118 msec, shelly-40:'Thomas Büro Licht'
Sep 15 09:28:43 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1726385323 - 'ping_SP_1'
Sep 15 09:28:43 srv4hai ping_SP[11252]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Sep 15 09:28:57 srv4hai dhcpd[30731]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:28:57 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:28:59 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Sep 15 09:28:59 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.5
Sep 15 09:28:59 srv4hai dhcpd[30731]: execute_statement argv[2] = btscan1
Sep 15 09:28:59 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:28:59 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f935063c890 192.168.12.252#42855/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55135/key rndc-key: signer "rndc-key" approved
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55135/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55135/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55135/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#55135/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Sep 15 09:28:59 srv4hai dhcpd[30731]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Sep 15 09:28:59 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f931c03b370 192.168.12.252#47175/key rndc-key: signer "rndc-key" approved
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f931c03b370 192.168.12.252#47175/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Sep 15 09:28:59 srv4hai named[30406]: client @0x7f931c03b370 192.168.12.252#47175/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.
Sep 15 09:28:59 srv4hai dhcpd[30731]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Sep 15 09:28:59 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Sep 15 09:29:01 srv4hai common[13482]: SHELLY common.sh inkludiert von ./bt_scan.sh
Sep 15 09:29:01 srv4hai CRON[13490]: (root) CMD (/etc/linetest/linetest.sh)
Sep 15 09:29:11 srv4hai bt_scan[13482]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Sep 15 09:29:11 srv4hai bt_scan[13482]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Sep 15 09:29:11 srv4hai common[13528]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 15 09:29:12 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'10'=1726385352-1726385342
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Sep 15 09:29:12 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'10'=1726385352-1726385342
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'presence'
Sep 15 09:29:12 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'10'=1726385352-1726385342
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Sep 15 09:29:12 srv4hai check_presence[13528]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Sep 15 09:29:13 srv4hai check_presence[13528]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_buero_UM) - sceneOFF(offAll)
Sep 15 09:29:13 srv4hai check_presence[13528]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Sep 15 09:29:16 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1726385356 - 'ping_SP_0'
Sep 15 09:29:16 srv4hai ping_SP[22372]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 15 09:29:46 srv4hai activate_scenario[13713]: SHELLY scenario: 'buero_UM(buero_um)' started
Sep 15 09:29:46 srv4hai activate_scenario[13713]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-40:'Thomas Büro Licht'

finished