activate_scenario.sh
Mon Apr 29 18:48:16 CEST 2024
scenario: nasszeile_um
source: 192.168.12.128

'day' <--> DayTime=checked , NightTime=checked
-> execution of scenario
-> sending email(s) to 'null'

scenario: 'Nasszeile UM-schalten' - 'nasszeile_um' - 'nasszeile_um'

Küche Licht Naßzeile (/srv/www/viehai/shelly/log_scenarios/2024/04/29/_0.log)

vorher: 18:48:16.492

http://192.168.12.116/white/0?turn=toggle&brightness=25
  "ison": true,
  "source": "http",
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "mode": "white",
  "brightness": 25,
  "transition": 0,
  "power": 0,
  "overpower": false

nachher: 18:48:16.576
Dauer: 84 msec phName: 'shelly-28:'Schalter Nasszeile''


Küche Licht Naßzeile (/srv/www/viehai/shelly/log_scenarios/2024/04/29/_1.log)

vorher: 18:48:16.510

http://192.168.12.116/white/1?turn=toggle&brightness=25
  "ison": true,
  "source": "http",
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "mode": "white",
  "brightness": 25,
  "transition": 0,
  "power": 0,
  "overpower": false

nachher: 18:48:16.573
Dauer: 62 msec phName: 'shelly-28:'Schalter Nasszeile''


scenario: 'Nasszeile UM-schalten' - 'nasszeile_um' - 'nasszeile_um'

urls : 2
vorher : 18:48:16.467
nachher: 18:48:16.585
Dauer : 118 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/04/29//scene_nasszeile_um_20240429_184816.txt'


syslog

Apr 29 18:45:01 srv4hai smartd[27489]: Device: /dev/sdd [SAT], SMART Usage Attribute: 194 Temperature_Celsius changed from 106 to 105
Apr 29 18:45:01 srv4hai CRON[5437]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 18:45:12 srv4hai dhcpd[22856]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Apr 29 18:45:12 srv4hai dhcpd[22856]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 18:45:12 srv4hai dhcpd[22856]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Apr 29 18:45:12 srv4hai dhcpd[22856]: execute_statement argv[1] = 192.168.12.61
Apr 29 18:45:12 srv4hai dhcpd[22856]: execute_statement argv[2] = fpThomas
Apr 29 18:45:12 srv4hai common[5442]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/smartphone.sh
Apr 29 18:45:12 srv4hai smartphone.sh: SHELLY - ping_SP.sh started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Apr 29 18:45:12 srv4hai common[5456]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//ping_SP.sh
Apr 29 18:45:12 srv4hai dhcpd[22856]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Apr 29 18:45:12 srv4hai dhcpd[22856]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f931801e8a0 192.168.12.252#53511/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#38815/key rndc-key: signer "rndc-key" approved
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#38815/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#38815/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#38815/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f9314033170 192.168.12.252#38815/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Apr 29 18:45:12 srv4hai dhcpd[22856]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f931c633cd0 192.168.12.252#35845/key rndc-key: signer "rndc-key" approved
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f931c633cd0 192.168.12.252#35845/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Apr 29 18:45:12 srv4hai named[15509]: client @0x7f931c633cd0 192.168.12.252#35845/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 29 18:45:12 srv4hai dhcpd[22856]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Apr 29 18:45:12 srv4hai ping_SP[5456]: SHELLY ping_SP.sh started from smartphone, user:'root'
Apr 29 18:45:13 srv4hai named[15509]: resolver priming query complete
Apr 29 18:45:18 srv4hai named[15509]: resolver priming query complete
Apr 29 18:45:21 srv4hai common[5481]: SHELLY common.sh inkludiert von ./bt_scan.sh
Apr 29 18:45:21 srv4hai named[15509]: resolver priming query complete
Apr 29 18:45:22 srv4hai common[5503]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Apr 29 18:45:22 srv4hai named[15509]: resolver priming query complete
Apr 29 18:45:22 srv4hai common.sh: SHELLY: BTstatus[0]:'BTdweet_not_set' dweetAge:'1'=1714409122-1714409121
Apr 29 18:45:22 srv4hai check_presence[5503]: 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
Apr 29 18:45:22 srv4hai check_presence[5503]: SHELLY (ping_SP__smartphone)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'coming'
Apr 29 18:45:22 srv4hai common.sh: SHELLY: BTstatus[1]:'BTdweet_not_set' dweetAge:'1'=1714409122-1714409121
Apr 29 18:45:22 srv4hai check_presence[5503]: 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
Apr 29 18:45:22 srv4hai common.sh: SHELLY: BTstatus[2]:'BTdweet_not_set' dweetAge:'1'=1714409122-1714409121
Apr 29 18:45:22 srv4hai check_presence[5503]: 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
Apr 29 18:45:22 srv4hai check_presence[5503]: SHELLY (ping_SP__smartphone)-(0) what: 'coming' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Apr 29 18:45:22 srv4hai check_presence[5503]: SHELLY (ping_SP__smartphone)-(0)-coming: activate_scenario.shtml: vorraumOn
Apr 29 18:45:22 srv4hai activate_scenario[5611]: SHELLY scenario: 'vorraumOn(vorraumon)' started
Apr 29 18:45:22 srv4hai activate_scenario[5611]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - phoneName:'0'
Apr 29 18:45:22 srv4hai activate_scenario[5611]: SHELLY scenario: 'vorraumOn(vorraumon)' TO BE EXECUTED, phoneName:'0'
Apr 29 18:45:22 srv4hai postfix/pickup[25647]: C49EF631A0: uid=33 from=
Apr 29 18:45:22 srv4hai postfix/cleanup[5661]: C49EF631A0: message-id=<20240429164522.C49EF631A0@mail.viehai.at>
Apr 29 18:45:22 srv4hai postfix/qmgr[15291]: C49EF631A0: from=, size=6732, nrcpt=1 (queue active)
Apr 29 18:45:22 srv4hai activate_scenario[5611]: SHELLY: scenario: 'vorraumOn(vorraumon)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Apr 29 18:45:22 srv4hai proc_url[5678]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/29/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Apr 29 18:45:22 srv4hai activate_scenario[5611]: SHELLY scenario: 'vorraumOn(vorraumon)' took 98 msec, phoneName:'0'
Apr 29 18:45:22 srv4hai ping_SP[5456]: SHELLY check_presence.sh coming 0 ping_SP__smartphone
Apr 29 18:45:23 srv4hai postfix/relay/smtp[5674]: C49EF631A0: to=, relay=mx04.nic.name[209.131.160.34]:25, delay=0.79, delays=0.02/0.01/0.49/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 61C24800FD)
Apr 29 18:45:23 srv4hai postfix/qmgr[15291]: C49EF631A0: removed
Apr 29 18:45:24 srv4hai named[15509]: resolver priming query complete
Apr 29 18:45:24 srv4hai ping_SP[5456]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1714409124
Apr 29 18:45:31 srv4hai bt_scan[5481]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'Yes'
Apr 29 18:45:31 srv4hai bt_scan[5481]: SHELLY - ping_SP.sh already running for BTmac: '0' - IPv4: '192.168.12.61'
Apr 29 18:45:38 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1714409138 - 'ping_SP_0'
Apr 29 18:45:38 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 18:45:39 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:01 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Apr 29 18:46:01 srv4hai CRON[5763]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 18:46:03 srv4hai fetchmail[1651]: 43126 messages (43125 seen) for tmiglinci at mail.akis.at (-701189538 octets).
Apr 29 18:46:03 srv4hai postfix/smtpd[5768]: connect from localhost[127.0.0.1]
Apr 29 18:46:03 srv4hai postfix/smtpd[5768]: 2313E6022E: client=localhost[127.0.0.1]
Apr 29 18:46:03 srv4hai postfix/cleanup[5661]: 2313E6022E: message-id=<20240429164522.C49EF631A0@mail.viehai.at>
Apr 29 18:46:03 srv4hai postfix/qmgr[15291]: 2313E6022E: from=, size=8006, nrcpt=1 (queue active)
Apr 29 18:46:03 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:43126 of 43126 (7709 octets) not flushed
Apr 29 18:46:03 srv4hai postfix/smtpd[5768]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 29 18:46:03 srv4hai postfix/local[5770]: 2313E6022E: to=, relay=local, delay=0.08, delays=0.05/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 18:46:03 srv4hai postfix/qmgr[15291]: 2313E6022E: removed
Apr 29 18:46:03 srv4hai fetchmail[1651]: 10183 messages (10183 seen) for srozum at mail.akis.at (1228518637 octets).
Apr 29 18:46:04 srv4hai fetchmail[1651]: 23180 messages (23179 seen) for mrozum at mail.akis.at (-290114212 octets).
Apr 29 18:46:04 srv4hai postfix/smtpd[5768]: connect from localhost[127.0.0.1]
Apr 29 18:46:04 srv4hai postfix/smtpd[5768]: 3C1406022E: client=localhost[127.0.0.1]
Apr 29 18:46:04 srv4hai postfix/cleanup[5661]: 3C1406022E: message-id=<0102018f2abe21fd-4ff0fcd0-a2a9-4f31-ba3c-08033dad8f50-000000@eu-west-1.amazonses.com>
Apr 29 18:46:04 srv4hai fetchmail[1651]: reading message mrozum@mail.akis.at:23180 of 23180 (83037 octets) not flushed
Apr 29 18:46:04 srv4hai postfix/qmgr[15291]: 3C1406022E: from=, size=83260, nrcpt=1 (queue active)
Apr 29 18:46:04 srv4hai postfix/smtpd[5768]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Apr 29 18:46:04 srv4hai postfix/local[5770]: 3C1406022E: to=, relay=local, delay=0.09, delays=0.07/0/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 18:46:04 srv4hai postfix/qmgr[15291]: 3C1406022E: removed
Apr 29 18:46:04 srv4hai fetchmail[1651]: 50 messages (50 seen) for admin@bastelstube.wien at pop3.world4you.com (12362835 octets).
Apr 29 18:46:06 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:11 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:13 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:15 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:18 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=5776, secured, session=
Apr 29 18:46:18 srv4hai dovecot: imap(miglincit)<5776>: Logged out in=595 out=39347 deleted=0 expunged=0 trashed=0 hdr_count=50 hdr_bytes=15057 body_count=0 body_bytes=0
Apr 29 18:46:29 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:30 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:36 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:38 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:40 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:48 srv4hai named[15509]: resolver priming query complete
Apr 29 18:46:51 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1714409211 - 'ping_SP_0'
Apr 29 18:46:51 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 18:47:01 srv4hai CRON[5806]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY scenario: 'herdzeile_UM(herdzeile_um)' started
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-29:'Schalter Herdzeile'
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY scenario: 'herdzeile_UM(herdzeile_um)' TO BE EXECUTED, shelly-29:'Schalter Herdzeile'
Apr 29 18:47:15 srv4hai postfix/pickup[25647]: 7748F631A0: uid=33 from=
Apr 29 18:47:15 srv4hai postfix/cleanup[5661]: 7748F631A0: message-id=<20240429164715.7748F631A0@mail.viehai.at>
Apr 29 18:47:15 srv4hai postfix/qmgr[15291]: 7748F631A0: from=, size=12330, nrcpt=1 (queue active)
Apr 29 18:47:15 srv4hai postfix/local[5770]: 7748F631A0: to=, orig_to=, relay=local, delay=0.03, delays=0.02/0/0/0.02, dsn=5.1.1, status=bounced (unknown user: "null")
Apr 29 18:47:15 srv4hai postfix/cleanup[5661]: 7CF55631A1: message-id=<20240429164715.7CF55631A1@mail.viehai.at>
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY: scenario: 'herdzeile_UM(herdzeile_um)'-'shelly-17'-'Küche Licht Herdzeile' --> '0?turn=toggle&brightness=50'
Apr 29 18:47:15 srv4hai proc_url[5883]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/29/_0.log' - shellyUsage:'Küche Licht Herdzeile' - scUrl:'http://192.168.12.117/white/0?turn=toggle&brightness=50' - phName:'shelly-29:'Schalter Herdzeile''
Apr 29 18:47:15 srv4hai postfix/bounce[5876]: 7748F631A0: sender non-delivery notification: 7CF55631A1
Apr 29 18:47:15 srv4hai postfix/qmgr[15291]: 7CF55631A1: from=<>, size=14212, nrcpt=1 (queue active)
Apr 29 18:47:15 srv4hai postfix/qmgr[15291]: 7748F631A0: removed
Apr 29 18:47:15 srv4hai postfix/local[5770]: 7CF55631A1: to=, relay=local, delay=0.01, delays=0.01/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Apr 29 18:47:15 srv4hai postfix/qmgr[15291]: 7CF55631A1: removed
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY: scenario: 'herdzeile_UM(herdzeile_um)'-'shelly-17'-'Küche Licht Herdzeile' --> '1?turn=toggle&brightness=50'
Apr 29 18:47:15 srv4hai proc_url[5898]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/04/29/_1.log' - shellyUsage:'Küche Licht Herdzeile' - scUrl:'http://192.168.12.117/white/1?turn=toggle&brightness=50' - phName:'shelly-29:'Schalter Herdzeile''
Apr 29 18:47:15 srv4hai activate_scenario[5815]: SHELLY scenario: 'herdzeile_UM(herdzeile_um)' took 169 msec, shelly-29:'Schalter Herdzeile'
Apr 29 18:47:25 srv4hai named[15509]: resolver priming query complete
Apr 29 18:47:40 srv4hai postfix/smtpd[5768]: connect from modemcable098.146-70-69.static.videotron.ca[69.70.146.98]
Apr 29 18:47:40 srv4hai postfix/smtpd[5768]: warning: modemcable098.146-70-69.static.videotron.ca[69.70.146.98]: SASL LOGIN authentication failed: Invalid authentication mechanism
Apr 29 18:47:40 srv4hai postfix/smtpd[5768]: disconnect from modemcable098.146-70-69.static.videotron.ca[69.70.146.98] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Apr 29 18:47:48 srv4hai named[15509]: resolver priming query complete
Apr 29 18:47:51 srv4hai postfix/smtpd[5768]: connect from unknown[194.33.191.13]
Apr 29 18:47:51 srv4hai postfix/smtpd[5768]: disconnect from unknown[194.33.191.13] ehlo=1 quit=1 commands=2
Apr 29 18:48:01 srv4hai CRON[5956]: (root) CMD (/etc/linetest/linetest.sh)
Apr 29 18:48:04 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1714409284 - 'ping_SP_0'
Apr 29 18:48:04 srv4hai ping_SP[5456]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Apr 29 18:48:10 srv4hai named[15509]: resolver priming query complete
Apr 29 18:48:13 srv4hai named[15509]: resolver priming query complete
Apr 29 18:48:16 srv4hai activate_scenario[5977]: SHELLY scenario: 'nasszeile_um(nasszeile_um)' started
Apr 29 18:48:16 srv4hai activate_scenario[5977]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - shelly-28:'Schalter Nasszeile'

finished