activate_scenario.sh
Fri Dec 27 11:19:18 CET 2024
scenario: vorraumOn
phoneName: 0

'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'thomas@miglinci.name'

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

VZ Licht (/srv/www/viehai/shelly/log_scenarios/2024/12/27/_0.log)

vorher: 11:19:18.853

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: 11:19:18.942
Dauer: 89 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 11:19:18.834
nachher: 11:19:18.948
Dauer : 115 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/27//scene_vorraumOn_20241227_111918.txt'


syslog

Dec 27 11:16:01 srv4hai CRON[7953]: (root) CMD (/etc/linetest/linetest.sh)
Dec 27 11:16:59 srv4hai dhcpd[4995]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:16:59 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:16:59 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 27 11:16:59 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.122
Dec 27 11:16:59 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-22
Dec 27 11:16:59 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:16:59 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f932c05b620 192.168.12.252#50963/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#52549/key rndc-key: signer "rndc-key" approved
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#52549/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#52549/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#52549/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93380d8d80 192.168.12.252#52549/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Dec 27 11:16:59 srv4hai dhcpd[4995]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#52133/key rndc-key: signer "rndc-key" approved
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#52133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Dec 27 11:16:59 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#52133/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Dec 27 11:16:59 srv4hai dhcpd[4995]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Dec 27 11:16:59 srv4hai common[7966]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 27 11:16:59 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Dec 27 11:17:00 srv4hai mosquitto[29419]: New connection from 192.168.12.122 on port 1883.
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Dec 27 11:17:00 srv4hai mosquitto[29419]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC Licht' already set for '192.168.12.122'
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Dec 27 11:17:00 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Dec 27 11:17:01 srv4hai CRON[8078]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Dec 27 11:17:01 srv4hai CRON[8079]: (root) CMD (/etc/linetest/linetest.sh)
Dec 27 11:17:30 srv4hai postfix/anvil[7925]: statistics: max connection rate 1/60s for (smtp:80.94.95.239) at Dec 27 11:14:07
Dec 27 11:17:30 srv4hai postfix/anvil[7925]: statistics: max connection count 1 for (smtp:80.94.95.239) at Dec 27 11:14:07
Dec 27 11:17:30 srv4hai postfix/anvil[7925]: statistics: max cache size 1 at Dec 27 11:14:07
Dec 27 11:18:00 srv4hai postfix/smtpd[8111]: connect from unknown[80.94.95.239]
Dec 27 11:18:01 srv4hai CRON[8115]: (root) CMD (/etc/linetest/linetest.sh)
Dec 27 11:18:03 srv4hai postfix/smtpd[8111]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 27 11:18:03 srv4hai postfix/smtpd[8111]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Dec 27 11:18:16 srv4hai dhcpd[4995]: DHCPDISCOVER from 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:18:16 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:18:16 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 27 11:18:16 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.122
Dec 27 11:18:16 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-22
Dec 27 11:18:16 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.122 (192.168.12.252) from 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:18:16 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.122 to 98:cd:ac:2e:1c:da via enp2s0
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93100dcb70 192.168.12.252#48525/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-22.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#56249/key rndc-key: signer "rndc-key" approved
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#56249/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' TXT
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#56249/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' TXT "0051fce7ef7edfb3d29643aefa7bc5b7f2"
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#56249/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-22.viehai.at' A
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f93500576c0 192.168.12.252#56249/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-22.viehai.at' A 192.168.12.122
Dec 27 11:18:16 srv4hai dhcpd[4995]: Added new forward map from shelly-22.viehai.at to 192.168.12.122
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#36681/key rndc-key: signer "rndc-key" approved
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#36681/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '122.12.168.192.in-addr.arpa' PTR
Dec 27 11:18:16 srv4hai named[30406]: client @0x7f933011a820 192.168.12.252#36681/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '122.12.168.192.in-addr.arpa' PTR shelly-22.viehai.at.
Dec 27 11:18:16 srv4hai dhcpd[4995]: Added reverse map from 122.12.168.192.in-addr.arpa. to shelly-22.viehai.at
Dec 27 11:18:16 srv4hai common[8130]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 27 11:18:16 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-22' - shIP/2:'' perform:'conf'
Dec 27 11:18:17 srv4hai mosquitto[29419]: New connection from 192.168.12.122 on port 1883.
Dec 27 11:18:17 srv4hai mosquitto[29419]: Client shelly1-98CDAC2E1CDA already connected, closing old connection.
Dec 27 11:18:17 srv4hai mosquitto[29419]: Socket error on client shelly1-98CDAC2E1CDA, disconnecting.
Dec 27 11:18:17 srv4hai mosquitto[29419]: New client connected from 192.168.12.122 as shelly1-98CDAC2E1CDA (c1, k60, u'shmqtt').
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-22' - shIP/2:'192.168.12.122'
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY: usage/name 'WC Licht' already set for '192.168.12.122'
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(0)' - allready transmitted - skipping
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(1)' - allready transmitted - skipping
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(2)' - allready transmitted - skipping
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(3)' - allready transmitted - skipping
Dec 27 11:18:18 srv4hai conf_shelly.sh: SHELLY:'shelly-22' 'conf(4)' - allready transmitted - skipping
Dec 27 11:19:01 srv4hai CRON[8266]: (root) CMD (/etc/linetest/linetest.sh)
Dec 27 11:19:07 srv4hai dhcpd[4995]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Dec 27 11:19:07 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 27 11:19:07 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Dec 27 11:19:07 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.61
Dec 27 11:19:07 srv4hai dhcpd[4995]: execute_statement argv[2] = fpThomas
Dec 27 11:19:07 srv4hai common[8277]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Dec 27 11:19:08 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Dec 27 11:19:08 srv4hai common[8292]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Dec 27 11:19:08 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Dec 27 11:19:08 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f9338088040 192.168.12.252#51775/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#52779/key rndc-key: signer "rndc-key" approved
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#52779/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#52779/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#52779/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#52779/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Dec 27 11:19:08 srv4hai dhcpd[4995]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f935010f2a0 192.168.12.252#33307/key rndc-key: signer "rndc-key" approved
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f935010f2a0 192.168.12.252#33307/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Dec 27 11:19:08 srv4hai named[30406]: client @0x7f935010f2a0 192.168.12.252#33307/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 27 11:19:08 srv4hai dhcpd[4995]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Dec 27 11:19:08 srv4hai ping_SP[8292]: SHELLY ping_SP.sh started from smartphone, user:'root'
Dec 27 11:19:18 srv4hai common[8326]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 27 11:19:18 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'2'=1735294758-1735294756
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Dec 27 11:19:18 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'2'=1735294758-1735294756
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Dec 27 11:19:18 srv4hai check_presence[8326]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Dec 27 11:19:18 srv4hai activate_scenario[8440]: SHELLY last scenario: 'offAll' @ '092444' from 'scene_offAll_20241227_092444.txt' - delta=26
Dec 27 11:19:18 srv4hai activate_scenario[8440]:        dateutils.ddiff -i '%Y%m%d%H%M%s' 20241227111918 20241227092444  -f '%S'
Dec 27 11:19:18 srv4hai activate_scenario[8440]: SHELLY  new scenario: 'vorraumOn' different - GO ON
Dec 27 11:19:18 srv4hai activate_scenario[8440]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Dec 27 11:19:18 srv4hai activate_scenario[8440]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished