activate_scenario.sh
Fri Jan 19 14:11:35 CET 2024
scenario: LOG_kommen

scenario: 'LOG_EIN' - 'LOG_kommen' - 'log_kommen'

scenario: 'LOG_EIN' - 'LOG_kommen' - 'log_kommen'

urls : 0
vorher : 14:11:35.754
nachher: 14:11:35.760
Dauer : 7 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/01/19//scene_LOG_kommen_20240119_141135.txt'

syslog

Jan 19 14:08:01 srv4hai CRON[23817]: (root) CMD (/etc/linetest/linetest.sh)
Jan 19 14:08:02 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:14 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23828, secured, session=<1dk3LEwPALcAAAAAAAAAAAAAAAAAAAAB>
Jan 19 14:08:14 srv4hai dovecot: imap(miglincit)<23828><1dk3LEwPALcAAAAAAAAAAAAAAAAAAAAB>: Logged out in=395 out=3424 deleted=0 expunged=0 trashed=0 hdr_count=1 hdr_bytes=563 body_count=1 body_bytes=1386
Jan 19 14:08:14 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23830, secured, session=
Jan 19 14:08:14 srv4hai dovecot: imap(miglincit)<23830>: Logged out in=616 out=13749 deleted=0 expunged=0 trashed=1 hdr_count=1 hdr_bytes=341 body_count=0 body_bytes=0
Jan 19 14:08:18 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:08:18 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:08:19 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:23 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669703 - 'ping_SP_0'
Jan 19 14:08:26 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:27 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:29 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:31 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:32 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:33 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:39 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:41 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:43 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:44 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:46 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:47 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:48 srv4hai named[14530]: resolver priming query complete
Jan 19 14:08:53 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669733 - 'ping_SP_0'
Jan 19 14:09:01 srv4hai systemd[1]: Starting Clean php session files...
Jan 19 14:09:01 srv4hai systemd[1]: phpsessionclean.service: Succeeded.
Jan 19 14:09:01 srv4hai systemd[1]: Started Clean php session files.
Jan 19 14:09:01 srv4hai CRON[23947]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)
Jan 19 14:09:01 srv4hai CRON[23948]: (root) CMD (/etc/linetest/linetest.sh)
Jan 19 14:09:03 srv4hai named[14530]: resolver priming query complete
Jan 19 14:09:08 srv4hai named[14530]: resolver priming query complete
Jan 19 14:09:19 srv4hai named[14530]: resolver priming query complete
Jan 19 14:09:22 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:09:22 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:09:24 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669763 - 'ping_SP_0'
Jan 19 14:09:41 srv4hai named[14530]: resolver priming query complete
Jan 19 14:09:41 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=23967, secured, session=
Jan 19 14:09:41 srv4hai dovecot: imap(miglincit)<23967>: Logged out in=527 out=1146 deleted=0 expunged=0 trashed=0 hdr_count=0 hdr_bytes=0 body_count=0 body_bytes=0
Jan 19 14:09:54 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669794 - 'ping_SP_0'
Jan 19 14:10:00 srv4hai dhcpd[8095]: DHCPDISCOVER from 30:83:98:8c:05:9d via enp2s0
Jan 19 14:10:00 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.123 to 30:83:98:8c:05:9d via enp2s0
Jan 19 14:10:00 srv4hai dhcpd[8095]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Jan 19 14:10:00 srv4hai dhcpd[8095]: execute_statement argv[1] = 192.168.12.123
Jan 19 14:10:00 srv4hai dhcpd[8095]: execute_statement argv[2] = shelly-23
Jan 19 14:10:00 srv4hai dhcpd[8095]: DHCPREQUEST for 192.168.12.123 (192.168.12.252) from 30:83:98:8c:05:9d via enp2s0
Jan 19 14:10:00 srv4hai dhcpd[8095]: DHCPACK on 192.168.12.123 to 30:83:98:8c:05:9d via enp2s0
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc328650140 192.168.12.252#54975/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-23.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#55477/key rndc-key: signer "rndc-key" approved
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#55477/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-23.viehai.at' TXT
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#55477/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-23.viehai.at' TXT "0067c4eae5f3400bd02ff9ebe320262416"
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#55477/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-23.viehai.at' A
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc310074f60 192.168.12.252#55477/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-23.viehai.at' A 192.168.12.123
Jan 19 14:10:00 srv4hai dhcpd[8095]: Added new forward map from shelly-23.viehai.at to 192.168.12.123
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc31400b700 192.168.12.252#49863/key rndc-key: signer "rndc-key" approved
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc31400b700 192.168.12.252#49863/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '123.12.168.192.in-addr.arpa' PTR
Jan 19 14:10:00 srv4hai named[14530]: client @0x7fc31400b700 192.168.12.252#49863/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '123.12.168.192.in-addr.arpa' PTR shelly-23.viehai.at.
Jan 19 14:10:00 srv4hai dhcpd[8095]: Added reverse map from 123.12.168.192.in-addr.arpa. to shelly-23.viehai.at
Jan 19 14:10:00 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-23' - shIP/2:'' workdir:'/srv/www/viehai/shelly' perform:'conf'
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-23' - shIP/2:'192.168.12.123'
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY: usage/name 'Licht Sekretär' already set for '192.168.12.123'
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(0)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(1)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(2)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(3)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(4)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' 'conf(5)' - allready transmitted - skipping
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' out:0 ON : MISSING
Jan 19 14:10:01 srv4hai conf_shelly.sh: SHELLY:'shelly-23' out:0 OFF: MISSING
Jan 19 14:10:02 srv4hai CRON[24118]: (root) CMD (/etc/linetest/linetest.sh)
Jan 19 14:10:02 srv4hai CRON[24119]: (www-data) CMD (/srv/www/viehai/shelly/meter.sh)
Jan 19 14:10:02 srv4hai meter.sh: SHELLY meter.sh started
Jan 19 14:10:14 srv4hai named[14530]: resolver priming query complete
Jan 19 14:10:19 srv4hai named[14530]: resolver priming query complete
Jan 19 14:10:24 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669824 - 'ping_SP_0'
Jan 19 14:10:24 srv4hai named[14530]: resolver priming query complete
Jan 19 14:10:26 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:10:26 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:10:50 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' TO BE EXECUTED, phone=''
Jan 19 14:10:50 srv4hai postfix/pickup[21571]: 11CAD60244: uid=33 from=
Jan 19 14:10:50 srv4hai postfix/cleanup[24498]: 11CAD60244: message-id=<20240119131050.11CAD60244@mail.viehai.at>
Jan 19 14:10:50 srv4hai postfix/qmgr[28610]: 11CAD60244: from=, size=10315, nrcpt=1 (queue active)
Jan 19 14:10:50 srv4hai activate_scenario.sh: SHELLY: 'shelly-08'-'Thomas Büro Regallicht' --> '0?turn=toggle'
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/19/_0.log' - shellyUsage:'Thomas Büro Regallicht' - scUrl:'http://192.168.12.108/relay/0?turn=toggle' - phName:'noHandy'
Jan 19 14:10:50 srv4hai postfix/local[24512]: 11CAD60244: to=, orig_to=, relay=local, delay=0.03, delays=0.02/0/0/0.01, dsn=5.1.1, status=bounced (unknown user: "null")
Jan 19 14:10:50 srv4hai postfix/cleanup[24498]: 1862A60245: message-id=<20240119131050.1862A60245@mail.viehai.at>
Jan 19 14:10:50 srv4hai postfix/bounce[24513]: 11CAD60244: sender non-delivery notification: 1862A60245
Jan 19 14:10:50 srv4hai postfix/qmgr[28610]: 1862A60245: from=<>, size=12194, nrcpt=1 (queue active)
Jan 19 14:10:50 srv4hai postfix/qmgr[28610]: 11CAD60244: removed
Jan 19 14:10:50 srv4hai postfix/local[24512]: 1862A60245: to=, relay=local, delay=0.01, delays=0/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Jan 19 14:10:50 srv4hai postfix/qmgr[28610]: 1862A60245: removed
Jan 19 14:10:50 srv4hai activate_scenario.sh: SHELLY: 'shelly-40'-'Thomas Büro Licht' --> '0?turn=toggle'
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: OF:'/srv/www/viehai/shelly/log_scenarios/2024/01/19/_1.log' - shellyUsage:'Thomas Büro Licht' - scUrl:'http://192.168.12.140/relay/0?turn=toggle' - phName:'noHandy'
Jan 19 14:10:50 srv4hai activate_scenario.sh: SHELLY scenario: 'buero_UM(buero_um)' took 82 msec, phone=''
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.108/status'
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.108","rssi":-63},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1705669850,"serial":1,"has_update":false,"mac":"8CAAB56203DF","cfg_changed_cnt":0,"actions_stats":{"skipped":0},"relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"http"}],"meters":[{"power":0.00,"is_valid":true}],"inputs":[{"input":1,"event":"","event_cnt":0}],"ext_sensors":{},"ext_temperature":{},"ext_humidity":{},"update":{"status":"idle","has_update":false,"new_version":"20230913-112003/v1.14.0-gcb84623","old_version":"20230913-112003/v1.14.0-gcb84623","beta_version":"20231107-162940/v1.14.1-rc1-g0617c15"},"ram_total":51688,"ram_free":40364,"fs_size":233681,"fs_free":150600,"uptime":6738033}'
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: grab status via - 'http://192.168.12.140/status'
Jan 19 14:10:50 srv4hai proc_url.sh: SHELLY: status: '{"wifi_sta":{"connected":true,"ssid":"viehai","ip":"192.168.12.140","rssi":-61},"cloud":{"enabled":false,"connected":false},"mqtt":{"connected":false},"time":"14:10","unixtime":1705669850,"serial":787,"has_update":false,"mac":"98CDAC307B4F","cfg_changed_cnt":0,"actions_stats":{"skipped":0},"relays":[{"ison":false,"has_timer":false,"timer_started":0,"timer_duration":0,"timer_remaining":0,"source":"http"}],"meters":[{"power":0.00,"is_valid":false,"timestamp":1705673450,"counters":[0.000, 0.000, 0.000],"total":0}],"inputs":[{"input":0,"event":"S","event_cnt":322},{"input":0,"event":"","event_cnt":0}],"temperature":49.09,"overtemperature":false,"tmp":{"tC":49.09,"tF":120.36,"is_valid":true},"update":{"status":"idle","has_update":false,"new_version":"20230913-112054/v1.14.0-gcb84623","old_version":"20230913-112054/v1.14.0-gcb84623","beta_version":"20231107-163031/v1.14.1-rc1-g0617c15"},"ram_total":51912,"ram_free":32052,"fs_size":233681,"fs_free":162648,"uptime":6737976}'
Jan 19 14:10:54 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669854 - 'ping_SP_0'
Jan 19 14:11:00 srv4hai named[14530]: resolver priming query complete
Jan 19 14:11:01 srv4hai CRON[24599]: (root) CMD (/etc/linetest/linetest.sh)
Jan 19 14:11:04 srv4hai named[14530]: resolver priming query complete
Jan 19 14:11:05 srv4hai named[14530]: resolver priming query complete
Jan 19 14:11:08 srv4hai meter.sh: SHELLY meter.sh ended
Jan 19 14:11:15 srv4hai bt_scan.sh: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' found: 'Yes'
Jan 19 14:11:15 srv4hai named[14530]: resolver priming query complete
Jan 19 14:11:15 srv4hai bt_scan.sh: SHELLY - ping_SP.sh started for BTmac: '0' - IPv4: '192.168.12.61'
Jan 19 14:11:15 srv4hai ping_SP.sh: SHELLY ping_SP.sh started
Jan 19 14:11:15 srv4hai check_presence.sh: SHELLY (0) found './meter/ping_SP_0' more than one (1) line for what: 'coming'
Jan 19 14:11:15 srv4hai check_presence.sh: SHELLY (0) set what to 'presence'
Jan 19 14:11:15 srv4hai check_presence.sh: SHELLY (0) what: 'presence' - persPresent: 'scanBT/ping, false/no IP, false/no IP,' - anyPresent: 'true'
Jan 19 14:11:15 srv4hai check_presence.sh: SHELLY (0)-presence: NOT activate_scenario.shtml: offAll
Jan 19 14:11:15 srv4hai ping_SP.sh: SHELLY - running process-id(24649): '24649 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61#01227034 /bin/bash /srv/www/viehai/shelly//ping_SP.sh 192.168.12.61' - 'ping_SP_0' - exiting ==============
Jan 19 14:11:19 srv4hai named[14530]: resolver priming query complete
Jan 19 14:11:24 srv4hai ping_SP.sh: SHELLY (0) - ping 192.168.12.61 0 sucessfully  @~1705669884 - 'ping_SP_0'
Jan 19 14:11:30 srv4hai dhcpd[8095]: DHCPDISCOVER from 00:22:61:a1:fb:40 via enp2s0
Jan 19 14:11:30 srv4hai dhcpd[8095]: DHCPOFFER on 192.168.12.17 to 00:22:61:a1:fb:40 via enp2s0

finished