activate_scenario.sh
Thu Aug 15 20:09:44 CEST 2024
scenario: vorraumOn
phoneName: 0

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

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

Licht Vorzimmer (/srv/www/viehai/shelly/log_scenarios/2024/08/15/_0.log)

vorher: 20:09:44.418

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: 20:09:44.505
Dauer: 87 msec phName: 'phoneName:'0''


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

urls : 1
vorher : 20:09:44.399
nachher: 20:09:44.511
Dauer : 113 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/08/15//scene_vorraumOn_20240815_200944.txt'


syslog

Aug 15 20:06:01 srv4hai CRON[29731]: (root) CMD (/etc/linetest/linetest.sh)
Aug 15 20:06:56 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=29747, secured, session=
Aug 15 20:06:56 srv4hai dovecot: imap(miglincit)<29747>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Aug 15 20:07:01 srv4hai CRON[29754]: (root) CMD (/etc/linetest/linetest.sh)
Aug 15 20:07:08 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Aug 15 20:07:09 srv4hai fetchmail[1651]: 50108 messages (50108 seen) for tmiglinci at mail.akis.at (-148582259 octets).
Aug 15 20:07:09 srv4hai fetchmail[1651]: 10584 messages (10584 seen) for srozum at mail.akis.at (1271851283 octets).
Aug 15 20:07:10 srv4hai fetchmail[1651]: 25337 messages (25336 seen) for mrozum at mail.akis.at (122409887 octets).
Aug 15 20:07:10 srv4hai postfix/smtpd[29771]: connect from localhost[127.0.0.1]
Aug 15 20:07:10 srv4hai postfix/smtpd[29771]: C1B1C6345A: client=localhost[127.0.0.1]
Aug 15 20:07:10 srv4hai postfix/cleanup[29774]: C1B1C6345A: message-id=<0.0.15.780.1DAEF3DC74CC126.0@mta-2d5675a9.ip4.emsmtp.us>
Aug 15 20:07:10 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1723745230 - 'ping_SP_1'
Aug 15 20:07:10 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Aug 15 20:07:10 srv4hai fetchmail[1651]: reading message mrozum@mail.akis.at:25337 of 25337 (146727 octets) not flushed
Aug 15 20:07:10 srv4hai postfix/qmgr[8566]: C1B1C6345A: from=, size=146993, nrcpt=1 (queue active)
Aug 15 20:07:10 srv4hai postfix/smtpd[29771]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Aug 15 20:07:10 srv4hai postfix/local[29789]: C1B1C6345A: to=, relay=local, delay=0.15, delays=0.12/0.02/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Aug 15 20:07:10 srv4hai postfix/qmgr[8566]: C1B1C6345A: removed
Aug 15 20:07:11 srv4hai common[29792]: SHELLY common.sh inkludiert von ./bt_scan.sh
Aug 15 20:07:11 srv4hai fetchmail[1651]: 1139 messages (1139 seen) for admin@bastelstube.wien at pop3.world4you.com (51570784 octets).
Aug 15 20:07:21 srv4hai bt_scan[29792]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Aug 15 20:07:21 srv4hai bt_scan[29792]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Aug 15 20:07:21 srv4hai common[29826]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 15 20:07:21 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'10'=1723745241-1723745231
Aug 15 20:07:21 srv4hai check_presence[29826]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Aug 15 20:07:21 srv4hai common.sh: SHELLY: BTstatus[1]:'On' dweetAge:'10'=1723745241-1723745231
Aug 15 20:07:21 srv4hai check_presence[29826]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and On//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Aug 15 20:07:21 srv4hai check_presence[29826]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'presence'
Aug 15 20:07:22 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'11'=1723745242-1723745231
Aug 15 20:07:22 srv4hai check_presence[29826]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Aug 15 20:07:22 srv4hai check_presence[29826]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/__) (BT/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Aug 15 20:07:22 srv4hai check_presence[29826]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_nasszeile_um) - sceneOFF(offAll)
Aug 15 20:07:22 srv4hai check_presence[29826]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Aug 15 20:08:01 srv4hai CRON[29983]: (root) CMD (/etc/linetest/linetest.sh)
Aug 15 20:08:24 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1723745304 - 'ping_SP_1'
Aug 15 20:08:24 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Aug 15 20:08:40 srv4hai dhcpd[30731]: DHCPDISCOVER from 84:cc:a8:a8:55:7d via enp2s0
Aug 15 20:08:40 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.102 to 84:cc:a8:a8:55:7d via enp2s0
Aug 15 20:08:40 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Aug 15 20:08:40 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.102
Aug 15 20:08:40 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-02
Aug 15 20:08:40 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.102 (192.168.12.252) from 84:cc:a8:a8:55:7d via enp2s0
Aug 15 20:08:40 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.102 to 84:cc:a8:a8:55:7d via enp2s0
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f933011e890 192.168.12.252#57299/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-02.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#58317/key rndc-key: signer "rndc-key" approved
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#58317/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-02.viehai.at' TXT
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#58317/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-02.viehai.at' TXT "005dc8ffbb18e92d4c1a5981a9354b73fe"
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#58317/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-02.viehai.at' A
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f935064b690 192.168.12.252#58317/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-02.viehai.at' A 192.168.12.102
Aug 15 20:08:40 srv4hai dhcpd[30731]: Added new forward map from shelly-02.viehai.at to 192.168.12.102
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f93300c1420 192.168.12.252#53319/key rndc-key: signer "rndc-key" approved
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f93300c1420 192.168.12.252#53319/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '102.12.168.192.in-addr.arpa' PTR
Aug 15 20:08:40 srv4hai named[30406]: client @0x7f93300c1420 192.168.12.252#53319/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '102.12.168.192.in-addr.arpa' PTR shelly-02.viehai.at.
Aug 15 20:08:40 srv4hai dhcpd[30731]: Added reverse map from 102.12.168.192.in-addr.arpa. to shelly-02.viehai.at
Aug 15 20:08:40 srv4hai common[30012]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Aug 15 20:08:40 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-02' - shIP/2:'' perform:'conf'
Aug 15 20:08:41 srv4hai mosquitto[18551]: New connection from 192.168.12.102 on port 1883.
Aug 15 20:08:41 srv4hai mosquitto[18551]: Client shelly1pm-84CCA8A8557D already connected, closing old connection.
Aug 15 20:08:41 srv4hai mosquitto[18551]: Socket error on client shelly1pm-84CCA8A8557D, disconnecting.
Aug 15 20:08:41 srv4hai mosquitto[18551]: New client connected from 192.168.12.102 as shelly1pm-84CCA8A8557D (c1, k60, u'shmqtt').
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-02' - shIP/2:'192.168.12.102'
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY: usage/name 'Balkon-Licht' already set for '192.168.12.102'
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' 'conf(0)' - allready transmitted - skipping
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' 'conf(1)' - allready transmitted - skipping
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' 'conf(2)' - allready transmitted - skipping
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' 'conf(3)' - allready transmitted - skipping
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' 'conf(4)' - allready transmitted - skipping
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' out:0 ON : MISSING
Aug 15 20:08:41 srv4hai conf_shelly.sh: SHELLY:'shelly-02' out:0 OFF: MISSING
Aug 15 20:09:01 srv4hai CRON[30142]: (root) CMD (/etc/linetest/linetest.sh)
Aug 15 20:09:01 srv4hai CRON[30143]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Aug 15 20:09:01 srv4hai systemd[1]: Starting Clean php session files...
Aug 15 20:09:01 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Aug 15 20:09:01 srv4hai systemd[1]: Started Clean php session files.
Aug 15 20:09:33 srv4hai common[30250]: SHELLY common.sh inkludiert von ./bt_scan.sh
Aug 15 20:09:37 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 1 sucessfully  @~1723745377 - 'ping_SP_1'
Aug 15 20:09:37 srv4hai ping_SP[23316]: SHELLY (1) - ping 192.168.12.62 pCnt:'1'
Aug 15 20:09:43 srv4hai bt_scan[30250]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Aug 15 20:09:43 srv4hai bt_scan[30250]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Aug 15 20:09:43 srv4hai common[30297]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Aug 15 20:09:43 srv4hai ping_SP[30297]: SHELLY ping_SP.sh started from bt_scan, user:'www-data'
Aug 15 20:09:43 srv4hai common[30318]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Aug 15 20:09:44 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'1'=1723745384-1723745383
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.TRY} and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Aug 15 20:09:44 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'1'=1723745384-1723745383
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and BTdweet_not_set//srv/www/viehai/shelly//meter/BTscan_BTid_1.Yes
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY (ping_SP__bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_1.LIVE' for what: 'coming'
Aug 15 20:09:44 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'1'=1723745384-1723745383
Aug 15 20:09:44 srv4hai check_presence[30318]: 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
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(__/__) (__/LIVE) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_nasszeile_um) - sceneONsmall(vorraumOn)
Aug 15 20:09:44 srv4hai check_presence[30318]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: vorraumOn
Aug 15 20:09:44 srv4hai activate_scenario[30466]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Aug 15 20:09:44 srv4hai activate_scenario[30466]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'

finished