activate_scenario.sh
Tue Apr 2 17:10:08 CEST 2024
scenario: LOG_kommen
source: 192.168.12.103
'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to ''
scenario: 'LOG_EIN' - 'LOG_kommen' - 'log_kommen'
scenario: 'LOG_EIN' - 'LOG_kommen' - 'log_kommen'
urls : 0
vorher : 17:10:08.335
nachher: 17:10:08.340
Dauer : 5 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/04/02//scene_LOG_kommen_20240402_171008.txt'
syslog
Apr 2 17:07:01 srv4hai CRON[18689]: (root) CMD (/etc/linetest/linetest.sh)
Apr 2 17:07:08 srv4hai named[15509]: resolver priming query complete
Apr 2 17:07:33 srv4hai dhcpd[5037]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:33 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:34 srv4hai dhcpd[5037]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:34 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:34 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Apr 2 17:07:34 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.5
Apr 2 17:07:34 srv4hai dhcpd[5037]: execute_statement argv[2] = btscan1
Apr 2 17:07:34 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:34 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f93180c5880 192.168.12.252#59223/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f92fc0061b0 192.168.12.252#55887/key rndc-key: signer "rndc-key" approved
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f92fc0061b0 192.168.12.252#55887/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f92fc0061b0 192.168.12.252#55887/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f92fc0061b0 192.168.12.252#55887/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f92fc0061b0 192.168.12.252#55887/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Apr 2 17:07:34 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Apr 2 17:07:34 srv4hai dhcpd[5037]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f93180107e0 192.168.12.252#34103/key rndc-key: signer "rndc-key" approved
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f93180107e0 192.168.12.252#34103/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Apr 2 17:07:34 srv4hai named[15509]: client @0x7f93180107e0 192.168.12.252#34103/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.
Apr 2 17:07:34 srv4hai dhcpd[5037]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Apr 2 17:07:34 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Apr 2 17:07:36 srv4hai named[15509]: resolver priming query complete
Apr 2 17:08:01 srv4hai CRON[18727]: (root) CMD (/etc/linetest/linetest.sh)
Apr 2 17:08:08 srv4hai named[15509]: resolver priming query complete
Apr 2 17:08:26 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=18741, secured, session=<2gJYeh4VZM8AAAAAAAAAAAAAAAAAAAAB>
Apr 2 17:08:26 srv4hai dovecot: imap(miglincit)<18741><2gJYeh4VZM8AAAAAAAAAAAAAAAAAAAAB>: Logged out in=513 out=1284 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Apr 2 17:08:34 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 2 17:08:35 srv4hai fetchmail[1651]: 41125 messages (41124 seen) for tmiglinci at mail.akis.at (-869675779 octets).
Apr 2 17:08:35 srv4hai postfix/smtpd[18748]: connect from localhost[127.0.0.1]
Apr 2 17:08:35 srv4hai postfix/smtpd[18748]: CDA8560244: client=localhost[127.0.0.1]
Apr 2 17:08:35 srv4hai postfix/cleanup[18751]: CDA8560244: message-id=<0102018e9f599d08-75945616-af61-4b9c-8172-8db4813c7d7a-000000@eu-west-1.amazonses.com>
Apr 2 17:08:35 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:41125 of 41125 (119722 octets) not flushed
Apr 2 17:08:35 srv4hai postfix/qmgr[15291]: CDA8560244: from=, size=119945, nrcpt=1 (queue active)
Apr 2 17:08:35 srv4hai postfix/smtpd[18748]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 2 17:08:35 srv4hai postfix/local[18752]: CDA8560244: to=, relay=local, delay=0.11, delays=0.08/0.01/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 2 17:08:35 srv4hai postfix/qmgr[15291]: CDA8560244: removed
Apr 2 17:08:36 srv4hai fetchmail[1651]: 10079 messages (10079 seen) for srozum at mail.akis.at (1216781481 octets).
Apr 2 17:08:37 srv4hai fetchmail[1651]: 22596 messages (22596 seen) for mrozum at mail.akis.at (-382700010 octets).
Apr 2 17:08:37 srv4hai named[15509]: resolver priming query complete
Apr 2 17:08:37 srv4hai fetchmail[1651]: 46 messages (46 seen) for admin@bastelstube.wien at pop3.world4you.com (12059834 octets).
Apr 2 17:08:56 srv4hai dhcpd[5037]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:56 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:58 srv4hai dhcpd[5037]: DHCPDISCOVER from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:58 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:58 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Apr 2 17:08:58 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.5
Apr 2 17:08:58 srv4hai dhcpd[5037]: execute_statement argv[2] = btscan1
Apr 2 17:08:58 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.5 (192.168.12.252) from 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:58 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.5 to 08:3a:8d:d0:46:5a via enp2s0
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f92fc030df0 192.168.12.252#45749/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: btscan1.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f931405fa30 192.168.12.252#37417/key rndc-key: signer "rndc-key" approved
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f931405fa30 192.168.12.252#37417/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' TXT
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f931405fa30 192.168.12.252#37417/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' TXT "000118916176265854cff71f762ffe0635"
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f931405fa30 192.168.12.252#37417/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'btscan1.viehai.at' A
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f931405fa30 192.168.12.252#37417/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'btscan1.viehai.at' A 192.168.12.5
Apr 2 17:08:58 srv4hai dhcpd[5037]: Added new forward map from btscan1.viehai.at to 192.168.12.5
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f93180a69a0 192.168.12.252#52323/key rndc-key: signer "rndc-key" approved
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f93180a69a0 192.168.12.252#52323/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '5.12.168.192.in-addr.arpa' PTR
Apr 2 17:08:58 srv4hai named[15509]: client @0x7f93180a69a0 192.168.12.252#52323/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.
Apr 2 17:08:58 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.5'(5) device 'btscan1'
Apr 2 17:08:58 srv4hai dhcpd[5037]: Added reverse map from 5.12.168.192.in-addr.arpa. to btscan1.viehai.at
Apr 2 17:08:58 srv4hai dhcpcommit.sh: SHELLY device 'btscan1' known and accepted
Apr 2 17:08:58 srv4hai named[15509]: resolver priming query complete
Apr 2 17:09:00 srv4hai systemd[1]: Starting Clean php session files...
Apr 2 17:09:00 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Apr 2 17:09:00 srv4hai systemd[1]: Started Clean php session files.
Apr 2 17:09:01 srv4hai CRON[18875]: (root) CMD (/etc/linetest/linetest.sh)
Apr 2 17:09:01 srv4hai CRON[18876]: (root) CMD ( [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Apr 2 17:09:08 srv4hai named[15509]: resolver priming query complete
Apr 2 17:09:57 srv4hai dhcpd[5037]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 2 17:09:57 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 2 17:09:57 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 2 17:09:57 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Apr 2 17:09:57 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Apr 2 17:09:57 srv4hai common[18925]: SHELLY common.sh inkludiert
Apr 2 17:09:57 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 2 17:09:57 srv4hai common[18937]: SHELLY common.sh inkludiert
Apr 2 17:09:57 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 2 17:09:57 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931c12c1c0 192.168.12.252#39931/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931007d0f0 192.168.12.252#51453/key rndc-key: signer "rndc-key" approved
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931007d0f0 192.168.12.252#51453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931007d0f0 192.168.12.252#51453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931007d0f0 192.168.12.252#51453/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f931007d0f0 192.168.12.252#51453/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr 2 17:09:57 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f930800b8b0 192.168.12.252#50103/key rndc-key: signer "rndc-key" approved
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f930800b8b0 192.168.12.252#50103/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr 2 17:09:57 srv4hai named[15509]: client @0x7f930800b8b0 192.168.12.252#50103/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.
Apr 2 17:09:57 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr 2 17:09:57 srv4hai ping_SP[18937]: SHELLY ping_SP.sh started from smartphone
Apr 2 17:09:57 srv4hai named[15509]: resolver priming query complete
Apr 2 17:09:58 srv4hai named[15509]: resolver priming query complete
Apr 2 17:09:59 srv4hai named[15509]: resolver priming query complete
Apr 2 17:10:01 srv4hai CRON[18959]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Apr 2 17:10:01 srv4hai CRON[18960]: (root) CMD (/etc/linetest/linetest.sh)
Apr 2 17:10:01 srv4hai common[18961]: SHELLY common.sh inkludiert
Apr 2 17:10:01 srv4hai meter.sh: SHELLY meter.sh started
Apr 2 17:10:02 srv4hai meter.sh: SHELLY 'shelly-29/Schalter Herdzeile' - type: 'shelly-i3' ignoring
Apr 2 17:10:02 srv4hai meter.sh: SHELLY 'shelly-28/Schalter Nasszeile' - type: 'shelly-i3' ignoring
Apr 2 17:10:05 srv4hai meter.sh: SHELLY 'shelly-23/Licht Sekretär': OFFLINE, resp. not answering within connect-timeout 2.500000s
Apr 2 17:10:07 srv4hai named[15509]: resolver priming query complete
Apr 2 17:10:07 srv4hai common[19429]: SHELLY common.sh inkludiert
Apr 2 17:10:08 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'46'=1712070607-1712070561
Apr 2 17:10:08 srv4hai check_presence[19429]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Apr 2 17:10:08 srv4hai meter.sh: SHELLY 'shelly-13/Thomas Taster' - type: 'shelly-BTN' ignoring
Apr 2 17:10:08 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'47'=1712070608-1712070561
Apr 2 17:10:08 srv4hai check_presence[19429]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_1.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Apr 2 17:10:08 srv4hai meter.sh: SHELLY 'shelly-14/AUS-Schalter' - type: 'shelly-BTN' ignoring
Apr 2 17:10:08 srv4hai common.sh: SHELLY: BTstatus[2]:'Off' dweetAge:'47'=1712070608-1712070561
Apr 2 17:10:08 srv4hai check_presence[19429]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Apr 2 17:10:08 srv4hai meter.sh: SHELLY 'shelly-37/Klima Büro' - type: 'shelly-ht' ignoring
Apr 2 17:10:08 srv4hai check_presence[19429]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/IP) (__/IP) (__/__)' - anyPresent: 'true' - otherPresent:'true'
Apr 2 17:10:08 srv4hai check_presence[19429]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 2 17:10:08 srv4hai meter.sh: SHELLY 'shelly-38/Wetterwerte Innen' - status: 'OOS' ignoring
Apr 2 17:10:08 srv4hai meter.sh: SHELLY 'shelly-39/Wetterwerte Balkon' - type: 'shelly-ht-plus' ignoring
Apr 2 17:10:08 srv4hai activate_scenario[19634]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 2 17:10:08 srv4hai activate_scenario[19634]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
Apr 2 17:10:08 srv4hai activate_scenario[19634]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Apr 2 17:10:08 srv4hai postfix/pickup[32434]: 241FD60245: uid=33 from=
Apr 2 17:10:08 srv4hai postfix/cleanup[18751]: 241FD60245: message-id=<20240402151008.241FD60245@mail.viehai.at>
Apr 2 17:10:08 srv4hai postfix/qmgr[15291]: 241FD60245: from=, size=14973, nrcpt=1 (queue active)
Apr 2 17:10:08 srv4hai named[15509]: resolver priming query complete
Apr 2 17:10:08 srv4hai activate_scenario[19634]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Apr 2 17:10:08 srv4hai proc_url[19726]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/02/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Apr 2 17:10:08 srv4hai activate_scenario[19634]: SHELLY scenario: 'vorraumOn(vorraumon)' took 99 msec, phoneName:'0'
Apr 2 17:10:08 srv4hai ping_SP[18937]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Apr 2 17:10:08 srv4hai activate_scenario[19751]: SHELLY scenario: 'LOG_kommen(log_kommen)' started
Apr 2 17:10:08 srv4hai activate_scenario[19751]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-03:'Licht Vorzimmer'
finished