activate_scenario.sh
Sat Sep 7 19:39:33 CEST 2024
scenario: sekr_UM
source: 192.168.12.101
'night' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'
scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'
Licht Sekretär (/srv/www/viehai/shelly/log_scenarios/2024/09/07/_0.log)
vorher: 19:39:33.671
http://192.168.12.123/relay/0?turn=toggle |
|---|
"ison": true,
"has_timer": false,
"timer_started": 0,
"timer_duration": 0,
"timer_remaining": 0,
"source": "http"
|
nachher: 19:39:33.714
Dauer: 42 msec
phName: 'shelly-01:'Wandlicht beim Esstisch''
scenario: 'Sekretär Licht UM-schalten' - 'sekr_UM' - 'sekr_um'
urls : 1
vorher : 19:39:33.645
nachher: 19:39:33.720
Dauer : 76 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/09/07//scene_sekr_UM_20240907_193933.txt'
syslog
Sep 7 19:36:01 srv4hai CRON[8662]: (root) CMD (/etc/linetest/linetest.sh)
Sep 7 19:36:53 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=8686, secured, session=
Sep 7 19:36:53 srv4hai dovecot: imap(miglincit)<8686>: Logged out in=137 out=1088 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Sep 7 19:37:01 srv4hai CRON[8691]: (root) CMD (/etc/linetest/linetest.sh)
Sep 7 19:37:08 srv4hai postfix/anvil[8597]: statistics: max connection rate 1/60s for (smtp:94.141.120.187) at Sep 7 19:33:48
Sep 7 19:37:08 srv4hai postfix/anvil[8597]: statistics: max connection count 1 for (smtp:94.141.120.187) at Sep 7 19:33:48
Sep 7 19:37:08 srv4hai postfix/anvil[8597]: statistics: max cache size 1 at Sep 7 19:33:48
Sep 7 19:37:11 srv4hai postfix/smtpd[8696]: connect from unknown[94.141.120.187]
Sep 7 19:37:11 srv4hai postfix/smtpd[8696]: warning: unknown[94.141.120.187]: SASL LOGIN authentication failed: Invalid authentication mechanism
Sep 7 19:37:11 srv4hai postfix/smtpd[8696]: lost connection after AUTH from unknown[94.141.120.187]
Sep 7 19:37:11 srv4hai postfix/smtpd[8696]: disconnect from unknown[94.141.120.187] ehlo=1 auth=0/1 commands=1/2
Sep 7 19:38:01 srv4hai CRON[8714]: (root) CMD (/etc/linetest/linetest.sh)
Sep 7 19:38:24 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Sep 7 19:38:25 srv4hai fetchmail[1651]: 51795 messages (51795 seen) for tmiglinci at mail.akis.at (-28802234 octets).
Sep 7 19:38:26 srv4hai fetchmail[1651]: 10688 messages (10688 seen) for srozum at mail.akis.at (1290220297 octets).
Sep 7 19:38:26 srv4hai fetchmail[1651]: 25770 messages (25770 seen) for mrozum at mail.akis.at (189137412 octets).
Sep 7 19:38:27 srv4hai fetchmail[1651]: 1142 messages (1142 seen) for admin@bastelstube.wien at pop3.world4you.com (51614275 octets).
Sep 7 19:38:49 srv4hai dhcpd[30731]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Sep 7 19:38:49 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Sep 7 19:38:49 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Sep 7 19:38:49 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.61
Sep 7 19:38:49 srv4hai dhcpd[30731]: execute_statement argv[2] = fpThomas
Sep 7 19:38:49 srv4hai common[8729]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Sep 7 19:38:49 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Sep 7 19:38:49 srv4hai common[8743]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Sep 7 19:38:49 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Sep 7 19:38:49 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f9348035d30 192.168.12.252#39851/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45225/key rndc-key: signer "rndc-key" approved
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45225/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45225/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45225/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45225/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Sep 7 19:38:49 srv4hai dhcpd[30731]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f9348090720 192.168.12.252#41189/key rndc-key: signer "rndc-key" approved
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f9348090720 192.168.12.252#41189/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Sep 7 19:38:49 srv4hai named[30406]: client @0x7f9348090720 192.168.12.252#41189/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.
Sep 7 19:38:49 srv4hai dhcpd[30731]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Sep 7 19:38:49 srv4hai ping_SP[8743]: SHELLY ping_SP.sh started from smartphone, user:'root'
Sep 7 19:38:53 srv4hai common[8766]: SHELLY common.sh inkludiert von ./bt_scan.sh
Sep 7 19:38:53 srv4hai dhcpd[30731]: DHCPDISCOVER from e0:98:06:aa:08:24 via enp2s0
Sep 7 19:38:53 srv4hai dhcpd[30731]: DHCPOFFER on 192.168.12.103 to e0:98:06:aa:08:24 via enp2s0
Sep 7 19:38:53 srv4hai dhcpd[30731]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Sep 7 19:38:53 srv4hai dhcpd[30731]: execute_statement argv[1] = 192.168.12.103
Sep 7 19:38:53 srv4hai dhcpd[30731]: execute_statement argv[2] = shelly-03
Sep 7 19:38:53 srv4hai dhcpd[30731]: DHCPREQUEST for 192.168.12.103 (192.168.12.252) from e0:98:06:aa:08:24 via enp2s0
Sep 7 19:38:53 srv4hai dhcpd[30731]: DHCPACK on 192.168.12.103 to e0:98:06:aa:08:24 via enp2s0
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f93300ede20 192.168.12.252#34339/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-03.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#45265/key rndc-key: signer "rndc-key" approved
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#45265/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-03.viehai.at' TXT
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#45265/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-03.viehai.at' TXT "00860bad6f5defda4d37fa2e9f7a2f1749"
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#45265/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-03.viehai.at' A
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933001df50 192.168.12.252#45265/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-03.viehai.at' A 192.168.12.103
Sep 7 19:38:53 srv4hai dhcpd[30731]: Added new forward map from shelly-03.viehai.at to 192.168.12.103
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#34913/key rndc-key: signer "rndc-key" approved
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#34913/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '103.12.168.192.in-addr.arpa' PTR
Sep 7 19:38:53 srv4hai named[30406]: client @0x7f933004a8d0 192.168.12.252#34913/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '103.12.168.192.in-addr.arpa' PTR shelly-03.viehai.at.
Sep 7 19:38:53 srv4hai dhcpd[30731]: Added reverse map from 103.12.168.192.in-addr.arpa. to shelly-03.viehai.at
Sep 7 19:38:53 srv4hai common[8780]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Sep 7 19:38:53 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-03' - shIP/2:'' perform:'conf'
Sep 7 19:38:54 srv4hai mosquitto[18551]: New connection from 192.168.12.103 on port 1883.
Sep 7 19:38:54 srv4hai mosquitto[18551]: Client shelly1pm-E09806AA0824 already connected, closing old connection.
Sep 7 19:38:54 srv4hai mosquitto[18551]: Socket error on client shelly1pm-E09806AA0824, disconnecting.
Sep 7 19:38:54 srv4hai mosquitto[18551]: New client connected from 192.168.12.103 as shelly1pm-E09806AA0824 (c1, k60, u'shmqtt').
Sep 7 19:38:54 srv4hai conf_shelly.sh: SHELLY: PARAMS shName/1:'shelly-03' - shIP/2:'192.168.12.103'
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY: usage/name 'Licht Vorzimmer' already set for '192.168.12.103'
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(0)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(1)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(2)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(3)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(4)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(5)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(6)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' 'conf(7)' - allready transmitted - skipping
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' out:0 ON : MISSING
Sep 7 19:38:55 srv4hai conf_shelly.sh: SHELLY:'shelly-03' out:0 OFF: MISSING
Sep 7 19:38:59 srv4hai common[8955]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Sep 7 19:38:59 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'0'=1725730739-1725730739
Sep 7 19:38:59 srv4hai check_presence[8955]: 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
Sep 7 19:38:59 srv4hai check_presence[8955]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Sep 7 19:38:59 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'0'=1725730739-1725730739
Sep 7 19:38:59 srv4hai check_presence[8955]: 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
Sep 7 19:38:59 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'0'=1725730739-1725730739
Sep 7 19:38:59 srv4hai check_presence[8955]: 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
Sep 7 19:38:59 srv4hai check_presence[8955]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Sep 7 19:39:00 srv4hai check_presence[8955]: SHELLY - check 'coming': anyPresent(true) - lScene(scene_offAll) - sceneONsmall(vorraumOn)
Sep 7 19:39:00 srv4hai check_presence[8955]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Sep 7 19:39:00 srv4hai activate_scenario[9087]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Sep 7 19:39:00 srv4hai activate_scenario[9087]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Sep 7 19:39:00 srv4hai activate_scenario[9087]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Sep 7 19:39:00 srv4hai postfix/pickup[4782]: 1EF096345B: uid=33 from=
Sep 7 19:39:00 srv4hai systemd[1]: Starting Clean php session files...
Sep 7 19:39:00 srv4hai postfix/cleanup[9137]: 1EF096345B: message-id=<20240907173900.1EF096345B@mail.viehai.at>
Sep 7 19:39:00 srv4hai postfix/qmgr[8566]: 1EF096345B: from=, size=11836, nrcpt=1 (queue active)
Sep 7 19:39:00 srv4hai activate_scenario[9087]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Sep 7 19:39:00 srv4hai proc_url[9155]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/09/07/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Sep 7 19:39:00 srv4hai activate_scenario[9087]: SHELLY scenario: 'vorraumOn(vorraumon)' took 70 msec, phoneName:'0'
Sep 7 19:39:00 srv4hai ping_SP[8743]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Sep 7 19:39:00 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Sep 7 19:39:00 srv4hai systemd[1]: Started Clean php session files.
Sep 7 19:39:01 srv4hai postfix/relay/smtp[9151]: 1EF096345B: to=, relay=mx01.nic.name[209.131.159.47]:25, delay=1.1, delays=0.05/0.01/0.81/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 093196001C)
Sep 7 19:39:01 srv4hai postfix/qmgr[8566]: 1EF096345B: removed
Sep 7 19:39:01 srv4hai CRON[9279]: (root) CMD ( [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Sep 7 19:39:01 srv4hai CRON[9280]: (root) CMD (/etc/linetest/linetest.sh)
Sep 7 19:39:02 srv4hai ping_SP[8743]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1725730742
Sep 7 19:39:03 srv4hai bt_scan[8766]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Sep 7 19:39:03 srv4hai bt_scan[8766]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Sep 7 19:39:15 srv4hai ping_SP[8743]: SHELLY (0) - ping 192.168.12.61 0 sucessfully @~1725730755 - 'ping_SP_0'
Sep 7 19:39:15 srv4hai ping_SP[8743]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Sep 7 19:39:33 srv4hai activate_scenario[9348]: SHELLY scenario: 'sekr_UM(sekr_um)' started
Sep 7 19:39:33 srv4hai activate_scenario[9348]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-01:'Wandlicht beim Esstisch'
finished