activate_scenario.sh
Mon Feb 12 19:54:43 CET 2024
scenario: bedlight_tom
source: 192.168.12.112

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

scenario: 'Nachttischlampe Thomas UM-schalten' - 'bedlight_tom' - 'bedlight_tom'

Nachtkästchen Lampe Thomas (/srv/www/viehai/shelly/log_scenarios/2024/02/12/_0.log)

vorher: 19:54:43.150

http://192.168.12.107/relay/0?turn=toggle
  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 19:54:43.183
Dauer: 33 msec phName: 'shelly-12:'Schlafzimmer Licht''


scenario: 'Nachttischlampe Thomas UM-schalten' - 'bedlight_tom' - 'bedlight_tom'

urls : 1
vorher : 19:54:43.115
nachher: 19:54:43.189
Dauer : 74 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/02/12//scene_bedlight_tom_20240212_195443.txt'


syslog

Feb 12 19:51:01 srv4hai meter.sh: SHELLY 'shelly-44/44-TBD':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Feb 12 19:51:01 srv4hai CRON[4856]: (root) CMD (/etc/linetest/linetest.sh)
Feb 12 19:51:03 srv4hai meter.sh: SHELLY 'shelly-45/45-TBD':  OFFLINE, resp. not answering within connect-timeout 2.500000s
Feb 12 19:51:03 srv4hai meter.sh: SHELLY meter.sh ended
Feb 12 19:51:03 srv4hai postfix/pickup[2067]: ED74860244: uid=33 from=
Feb 12 19:51:03 srv4hai postfix/cleanup[4875]: ED74860244: message-id=<20240212185103.ED74860244@mail.viehai.at>
Feb 12 19:51:03 srv4hai postfix/qmgr[28610]: ED74860244: from=, size=654, nrcpt=1 (queue active)
Feb 12 19:51:04 srv4hai postfix/local[4877]: ED74860244: to=, orig_to=, relay=local, delay=0.05, delays=0.03/0.01/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Feb 12 19:51:04 srv4hai postfix/qmgr[28610]: ED74860244: removed
Feb 12 19:52:01 srv4hai CRON[4887]: (root) CMD (/etc/linetest/linetest.sh)
Feb 12 19:52:56 srv4hai activate_scenario[4900]: SHELLY scenario: 'LOG_kommen(log_kommen)' started
Feb 12 19:52:56 srv4hai activate_scenario[4900]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-03:'Licht Vorzimmer'
Feb 12 19:52:56 srv4hai activate_scenario[4900]: SHELLY scenario: 'LOG_kommen(log_kommen)' TO BE EXECUTED, shelly-03:'Licht Vorzimmer'
Feb 12 19:52:56 srv4hai activate_scenario[4900]: SHELLY scenario: 'LOG_kommen(log_kommen)' took 5 msec, shelly-03:'Licht Vorzimmer'
Feb 12 19:53:01 srv4hai CRON[4969]: (root) CMD (/etc/linetest/linetest.sh)
Feb 12 19:53:05 srv4hai common[4976]: SHELLY common.sh inkludiert
Feb 12 19:53:05 srv4hai bt_scan[4976]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / fixAddr: '192.168.12.61;' - found: 'Yes'
Feb 12 19:53:05 srv4hai bt_scan[4976]: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Feb 12 19:53:05 srv4hai common[5005]: SHELLY common.sh inkludiert
Feb 12 19:53:05 srv4hai ping_SP[5005]: SHELLY ping_SP.sh started from bt_scan
Feb 12 19:53:05 srv4hai common[5015]: SHELLY common.sh inkludiert
Feb 12 19:53:05 srv4hai check_presence[5015]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.DEAD and /srv/www/viehai/shelly//meter/BTscan_BTid_0.Yes
Feb 12 19:53:05 srv4hai check_presence[5015]: SHELLY (0) found  and 
Feb 12 19:53:05 srv4hai check_presence[5015]: SHELLY (0) found  and 
Feb 12 19:53:05 srv4hai check_presence[5015]: SHELLY (ping_SP__bt_scan)-(0) what: 'coming' - persPresent: '(BT/__) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Feb 12 19:53:05 srv4hai check_presence[5015]: SHELLY (ping_SP__bt_scan)-(0)-coming: activate_scenario.shtml: welcomeLight
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY scenario: 'welcomeLight(welcomelight)' started
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - phoneName:'0'
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY scenario: 'welcomeLight(welcomelight)' TO BE EXECUTED, phoneName:'0'
Feb 12 19:53:05 srv4hai postfix/pickup[2067]: C1F4A60244: uid=33 from=
Feb 12 19:53:05 srv4hai postfix/cleanup[5117]: C1F4A60244: message-id=<20240212185305.C1F4A60244@mail.viehai.at>
Feb 12 19:53:05 srv4hai postfix/qmgr[28610]: C1F4A60244: from=, size=7635, nrcpt=1 (queue active)
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY: scenario: 'welcomeLight(welcomelight)'-'shelly-03'-'Licht Vorzimmer' --> '0?turn=on'
Feb 12 19:53:05 srv4hai proc_url[5134]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/02/12/_0.log' - shellyUsage:'Licht Vorzimmer' - scUrl:'http://192.168.12.103/relay/0?turn=on' - phName:'phoneName:'0''
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY: scenario: 'welcomeLight(welcomelight)'-'shelly-16'-'Küche Licht Naßzeile' --> '0?turn=on&brightness=50'
Feb 12 19:53:05 srv4hai proc_url[5145]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/02/12/_1.log' - shellyUsage:'Küche Licht Naßzeile' - scUrl:'http://192.168.12.116/white/0?turn=on&brightness=50' - phName:'phoneName:'0''
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY: scenario: 'welcomeLight(welcomelight)'-'shelly-16'-'Küche Licht Naßzeile' --> '1?turn=on&brightness=50'
Feb 12 19:53:05 srv4hai proc_url[5156]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/02/12/_2.log' - shellyUsage:'Küche Licht Naßzeile' - scUrl:'http://192.168.12.116/white/1?turn=on&brightness=50' - phName:'phoneName:'0''
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY: scenario: 'welcomeLight(welcomelight)'-'shelly-17'-'Küche Licht Herdzeile' --> '0?turn=on&brightness=50'
Feb 12 19:53:05 srv4hai proc_url[5167]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/02/12/_3.log' - shellyUsage:'Küche Licht Herdzeile' - scUrl:'http://192.168.12.117/white/0?turn=on&brightness=50' - phName:'phoneName:'0''
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY: scenario: 'welcomeLight(welcomelight)'-'shelly-17'-'Küche Licht Herdzeile' --> '1?turn=on&brightness=50'
Feb 12 19:53:05 srv4hai proc_url[5178]: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/02/12/_4.log' - shellyUsage:'Küche Licht Herdzeile' - scUrl:'http://192.168.12.117/white/1?turn=on&brightness=50' - phName:'phoneName:'0''
Feb 12 19:53:05 srv4hai activate_scenario[5067]: SHELLY scenario: 'welcomeLight(welcomelight)' took 154 msec, phoneName:'0'
Feb 12 19:53:06 srv4hai ping_SP[5005]: SHELLY check_presence.sh coming 0 ping_SP__bt_scan
Feb 12 19:53:06 srv4hai ping_SP[5005]: SHELLY - new process for '192.168.12.61' - '/srv/www/viehai/shelly//meter/ping_SP_0' @~1707763986
Feb 12 19:53:06 srv4hai postfix/relay/smtp[5130]: C1F4A60244: to=, relay=mx02.nic.name[209.131.158.56]:25, delay=1, delays=0.05/0.01/0.71/0.27, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as 95AB820041)
Feb 12 19:53:06 srv4hai postfix/qmgr[28610]: C1F4A60244: removed
Feb 12 19:53:13 srv4hai dhcpd[5037]: DHCPDISCOVER from e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:13 srv4hai dhcpd[5037]: DHCPOFFER on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:13 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb 12 19:53:13 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Feb 12 19:53:13 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Feb 12 19:53:13 srv4hai common[5274]: SHELLY common.sh inkludiert
Feb 12 19:53:22 srv4hai ping_SP[5005]: SHELLY (0)- ping 192.168.12.61 0 not answered (1) @~1707764002 - 'ping_SP_0'
Feb 12 19:53:23 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb 12 19:53:23 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:23 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:23 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb 12 19:53:23 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Feb 12 19:53:23 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Feb 12 19:53:23 srv4hai common[5305]: SHELLY common.sh inkludiert
Feb 12 19:53:33 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb 12 19:53:33 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:33 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:33 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb 12 19:53:33 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Feb 12 19:53:33 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Feb 12 19:53:33 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb 12 19:53:33 srv4hai common[5326]: SHELLY common.sh inkludiert
Feb 12 19:53:43 srv4hai postfix/smtpd[5342]: connect from unknown[91.215.85.17]
Feb 12 19:53:43 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb 12 19:53:43 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:43 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:43 srv4hai dhcpd[5037]: execute_statement argv[0] = /srv/www/viehai/shelly/smartphone.sh
Feb 12 19:53:43 srv4hai dhcpd[5037]: execute_statement argv[1] = 192.168.12.61
Feb 12 19:53:43 srv4hai dhcpd[5037]: execute_statement argv[2] = fpThomas
Feb 12 19:53:43 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb 12 19:53:43 srv4hai named[14530]: client @0x7fc314083510 192.168.12.252#45365/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb 12 19:53:43 srv4hai common[5353]: SHELLY common.sh inkludiert
Feb 12 19:53:45 srv4hai ping_SP[5005]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1707764025 - 'ping_SP_0'
Feb 12 19:53:45 srv4hai ping_SP[5005]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Feb 12 19:53:47 srv4hai postfix/smtpd[5342]: warning: unknown[91.215.85.17]: SASL PLAIN authentication failed: 
Feb 12 19:53:47 srv4hai postfix/smtpd[5342]: disconnect from unknown[91.215.85.17] ehlo=2 starttls=1 auth=0/1 quit=1 unknown=0/1 commands=4/6
Feb 12 19:53:53 srv4hai smartphone.sh: SHELLY - ping_SP.sh already started for phoneIP: '192.168.12.61' / 'fpThomas' / '0'
Feb 12 19:53:53 srv4hai dhcpd[5037]: DHCPREQUEST for 192.168.12.61 (192.168.12.252) from e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:53 srv4hai dhcpd[5037]: DHCPACK on 192.168.12.61 to e8:78:29:c0:c2:4e via enp2s0
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: fpThomas.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' TXT
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' TXT "310ebd0eafccd9c2e311facf8a4b0e4ad2"
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'fpThomas.viehai.at' A
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'fpThomas.viehai.at' A 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added new forward map from fpThomas.viehai.at to 192.168.12.61
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/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.
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/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.
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/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.
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: signer "rndc-key" approved
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '61.12.168.192.in-addr.arpa' PTR
Feb 12 19:53:53 srv4hai named[14530]: client @0x7fc32811da30 192.168.12.252#41445/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.
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb 12 19:53:53 srv4hai dhcpd[5037]: Added reverse map from 61.12.168.192.in-addr.arpa. to fpThomas.viehai.at
Feb 12 19:54:01 srv4hai CRON[5388]: (root) CMD (/etc/linetest/linetest.sh)
Feb 12 19:54:43 srv4hai activate_scenario[5402]: SHELLY scenario: 'bedlight_tom(bedlight_tom)' started
Feb 12 19:54:43 srv4hai activate_scenario[5402]: SHELLY dayState='night' <--> DayTime=checked NightTime=checked - shelly-12:'Schlafzimmer Licht'

finished