activate_scenario.sh
Fri Jan 10 16:17:53 CET 2025
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'

TB Regallicht (/srv/www/viehai/shelly/log_scenarios/2025/01/10/_0.log)

vorher: 16:17:53.732

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: 16:17:53.778
Dauer: 46 msec phName: 'shelly-40:'TB Licht''


TB Licht (/srv/www/viehai/shelly/log_scenarios/2025/01/10/_1.log)

vorher: 16:17:53.751

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: 16:17:53.784
Dauer: 33 msec phName: 'shelly-40:'TB Licht''


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

urls : 2
vorher : 16:17:53.705
nachher: 16:17:53.791
Dauer : 85 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2025/01/10//scene_buero_UM_20250110_161753.txt'


syslog

Jan 10 16:14:00 srv4hai fetchmail[1651]: 61198 messages (61197 seen) for tmiglinci at mail.akis.at (720252717 octets).
Jan 10 16:14:00 srv4hai postfix/smtpd[25041]: connect from localhost[127.0.0.1]
Jan 10 16:14:00 srv4hai postfix/smtpd[25041]: 4C2176004E: client=localhost[127.0.0.1]
Jan 10 16:14:00 srv4hai postfix/cleanup[25044]: 4C2176004E: message-id=<0107019450c492ba-f2b90434-7470-4fd6-93b4-7fe706309cd8-000000@eu-central-1.amazonses.com>
Jan 10 16:14:00 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:61198 of 61198 (154423 octets) not flushed
Jan 10 16:14:00 srv4hai postfix/qmgr[8566]: 4C2176004E: from=, size=154643, nrcpt=1 (queue active)
Jan 10 16:14:00 srv4hai postfix/smtpd[25041]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Jan 10 16:14:00 srv4hai postfix/local[25045]: 4C2176004E: to=, relay=local, delay=0.12, delays=0.09/0.02/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 10 16:14:00 srv4hai postfix/qmgr[8566]: 4C2176004E: removed
Jan 10 16:14:00 srv4hai fetchmail[1651]: 11364 messages (11364 seen) for srozum at mail.akis.at (1356777446 octets).
Jan 10 16:14:01 srv4hai fetchmail[1651]: 28264 messages (28264 seen) for mrozum at mail.akis.at (557942537 octets).
Jan 10 16:14:01 srv4hai CRON[25048]: (root) CMD (/etc/linetest/linetest.sh)
Jan 10 16:14:01 srv4hai fetchmail[1651]: 1162 messages (1162 seen) for admin@bastelstube.wien at pop3.world4you.com (52206525 octets).
Jan 10 16:14:05 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1736522045 - 'ping_SP_1'
Jan 10 16:14:05 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 10 16:14:17 srv4hai named[30406]: resolver priming query complete
Jan 10 16:14:18 srv4hai named[30406]: resolver priming query complete
Jan 10 16:14:36 srv4hai common[25074]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jan 10 16:14:46 srv4hai bt_scan[25074]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'Yes'
Jan 10 16:14:46 srv4hai bt_scan[25074]: SHELLY - ping_SP.sh already running for BTmac: '1' - IPv4: '192.168.12.62'
Jan 10 16:14:53 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736522093 - 'ping_SP_0'
Jan 10 16:14:53 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 10 16:14:56 srv4hai named[30406]: resolver priming query complete
Jan 10 16:15:01 srv4hai CRON[25131]: (root) CMD (/etc/linetest/linetest.sh)
Jan 10 16:15:19 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1736522119 - 'ping_SP_1'
Jan 10 16:15:19 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 10 16:15:34 srv4hai named[30406]: resolver priming query complete
Jan 10 16:15:45 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=25170, secured, session=<61GjkVsrvqsAAAAAAAAAAAAAAAAAAAAB>
Jan 10 16:15:45 srv4hai dovecot: imap(miglincit)<25170><61GjkVsrvqsAAAAAAAAAAAAAAAAAAAAB>: Logged out in=1934 out=164727 deleted=0 expunged=0 trashed=0 hdr_count=253 hdr_bytes=91012 body_count=0 body_bytes=0
Jan 10 16:15:48 srv4hai named[30406]: resolver priming query complete
Jan 10 16:15:57 srv4hai named[30406]: resolver priming query complete
Jan 10 16:16:01 srv4hai CRON[25178]: (root) CMD (/etc/linetest/linetest.sh)
Jan 10 16:16:03 srv4hai common[25185]: SHELLY common.sh inkludiert von ./bt_scan.sh
Jan 10 16:16:07 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736522167 - 'ping_SP_0'
Jan 10 16:16:07 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 10 16:16:13 srv4hai bt_scan[25185]: SHELLY - who: 'Grete' / phoneName: 'S20_Grete' / '1' / shellyIP: '192.168.12.62' - found: 'No'
Jan 10 16:16:13 srv4hai bt_scan[25185]: SHELLY - lost BT-connection to BTmac: '1' found: 'No' - check presence
Jan 10 16:16:13 srv4hai common[25238]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Jan 10 16:16:13 srv4hai named[30406]: resolver priming query complete
Jan 10 16:16:14 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'4'=1736522174-1736522170
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Jan 10 16:16:14 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'4'=1736522174-1736522170
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (bt_scan)-(1) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'presence'
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (bt_scan)-(1) what: 'presence' - persPresent: '(BT/LIVE) (__/LIVE)' - anyPresent: 'true' - otherPresent:'true'
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_vorraumOn) - sceneOFF(offAll)
Jan 10 16:16:14 srv4hai check_presence[25238]: SHELLY (bt_scan)-(1)-presence: NOT activate_scenario.shtml: offAll
Jan 10 16:16:32 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1736522192 - 'ping_SP_1'
Jan 10 16:16:32 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 10 16:16:44 srv4hai common[25379]: SHELLY common.sh inkludiert von ./shelly/check_shelly.sh
Jan 10 16:16:44 srv4hai check_shelly.sh: SHELLY last scenery executed: 'vorraumOn'
Jan 10 16:16:44 srv4hai check_shelly.sh: SHELLY found '6' active shelly outputs
Jan 10 16:16:44 srv4hai common[25402]: SHELLY common.sh inkludiert von ./shelly/prep_viehai.sh
Jan 10 16:16:45 srv4hai common.sh: SHELLY: BTstatus[0]:'On' dweetAge:'5'=1736522205-1736522200
Jan 10 16:16:45 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'5'=1736522205-1736522200
Jan 10 16:16:59 srv4hai named[30406]: resolver priming query complete
Jan 10 16:17:01 srv4hai CRON[25463]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Jan 10 16:17:01 srv4hai CRON[25464]: (root) CMD (/etc/linetest/linetest.sh)
Jan 10 16:17:18 srv4hai dhcpd[4995]: DHCPDISCOVER from 2c:81:bf:a9:da:66 via enp2s0
Jan 10 16:17:19 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.30 to 2c:81:bf:a9:da:66 (iPhonevonJanna) via enp2s0
Jan 10 16:17:20 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1736522240 - 'ping_SP_0'
Jan 10 16:17:20 srv4hai ping_SP[23015]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Jan 10 16:17:20 srv4hai dhcpd[4995]: DHCPDISCOVER from 2c:81:bf:a9:da:66 (iPhonevonJanna) via enp2s0
Jan 10 16:17:20 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.30 to 2c:81:bf:a9:da:66 (iPhonevonJanna) via enp2s0
Jan 10 16:17:21 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.30 (192.168.12.252) from 2c:81:bf:a9:da:66 (iPhonevonJanna) via enp2s0
Jan 10 16:17:21 srv4hai dhcpd[4995]: Wrote 0 deleted host decls to leases file.
Jan 10 16:17:21 srv4hai dhcpd[4995]: Wrote 0 new dynamic host decls to leases file.
Jan 10 16:17:21 srv4hai dhcpd[4995]: Wrote 30 leases to leases file.
Jan 10 16:17:21 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.30 to 2c:81:bf:a9:da:66 (iPhonevonJanna) via enp2s0
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100e3a50 192.168.12.252#53413/key rndc-key: signer "rndc-key" approved
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100e3a50 192.168.12.252#53413/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhonevonJanna.viehai.at' A 192.168.12.30
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100e3a50 192.168.12.252#53413/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'iPhonevonJanna.viehai.at' TXT "31c5d9980b0b4d98690b2768453c2fbec7"
Jan 10 16:17:21 srv4hai dhcpd[4995]: Added new forward map from iPhonevonJanna.viehai.at to 192.168.12.30
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#44617/key rndc-key: signer "rndc-key" approved
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#44617/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '30.12.168.192.in-addr.arpa' PTR
Jan 10 16:17:21 srv4hai named[30406]: client @0x7f93100dab10 192.168.12.252#44617/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '30.12.168.192.in-addr.arpa' PTR iPhonevonJanna.viehai.at.
Jan 10 16:17:21 srv4hai dhcpd[4995]: Added reverse map from 30.12.168.192.in-addr.arpa. to iPhonevonJanna.viehai.at
Jan 10 16:17:45 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1736522265 - 'ping_SP_1'
Jan 10 16:17:45 srv4hai ping_SP[14529]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Jan 10 16:17:49 srv4hai postfix/smtpd[25523]: connect from unknown[154.203.197.178]
Jan 10 16:17:49 srv4hai postfix/smtpd[25523]: warning: unknown[154.203.197.178]: SASL LOGIN authentication failed: Invalid authentication mechanism
Jan 10 16:17:49 srv4hai postfix/smtpd[25523]: disconnect from unknown[154.203.197.178] ehlo=1 auth=0/1 quit=1 commands=2/3
Jan 10 16:17:53 srv4hai activate_scenario[25527]: SHELLY last scenario: 'vorraumOn' @ '160249' from 'scene_vorraumOn_20250110_160249.txt' - delta=-4
Jan 10 16:17:53 srv4hai activate_scenario[25527]:        dateutils.ddiff -i '%Y%m%d%H%M%s' 20250110161753 20250110160249  -f '%S'
Jan 10 16:17:53 srv4hai activate_scenario[25527]: SHELLY  new scenario: 'buero_UM' different - GO ON
Jan 10 16:17:53 srv4hai activate_scenario[25527]: SHELLY scenario: 'buero_UM(buero_um)' started
Jan 10 16:17:53 srv4hai activate_scenario[25527]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-40:'TB Licht'

finished