activate_scenario.sh
Sat Dec 14 10:05:37 CET 2024
scenario: pcON
origin: dhcpcommit.sh-pchai7x

scenario: 'Schreibtisch Thomas EIN-schalten' - 'pcON' - 'pcon'

TB Schreibtischlampe (/srv/www/viehai/shelly/log_scenarios/2024/12/14/_0.log)

vorher: 10:05:37.869

  "ison": true,
  "has_timer": false,
  "timer_started": 0,
  "timer_duration": 0,
  "timer_remaining": 0,
  "source": "http"

nachher: 10:05:37.913
Dauer: 43 msec phName: 'origin:'dhcpcommit.sh-pchai7x''

scenario: 'Schreibtisch Thomas EIN-schalten' - 'pcON' - 'pcon'

urls : 1
vorher : 10:05:37.844
nachher: 10:05:37.919
Dauer : 74 msec
outfile: '/srv/www/viehai/shelly/log_scenarios/2024/12/14//scene_pcON_20241214_100537.txt'

syslog

Dec 14 10:02:01 srv4hai CRON[26942]: (root) CMD (/etc/linetest/linetest.sh)
Dec 14 10:02:06 srv4hai bt_scan[26919]: SHELLY - who: 'Thomas' / phoneName: 'FP4_Thomas' / '0' / shellyIP: '192.168.12.61' - found: 'No'
Dec 14 10:02:06 srv4hai bt_scan[26919]: SHELLY - lost BT-connection to BTmac: '0' found: 'No' - check presence
Dec 14 10:02:06 srv4hai common[26971]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly//check_presence.sh
Dec 14 10:02:06 srv4hai named[30406]: resolver priming query complete
Dec 14 10:02:07 srv4hai common.sh: SHELLY: BTstatus[0]:'Off' dweetAge:'11'=1734166927-1734166916
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (0) found /srv/www/viehai/shelly//meter/ping_SP_0.LIVE and Off//srv/www/viehai/shelly//meter/BTscan_BTid_0.No
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (bt_scan)-(0) found '/srv/www/viehai/shelly//meter/ping_SP_0.LIVE' for what: 'presence'
Dec 14 10:02:07 srv4hai common.sh: SHELLY: BTstatus[1]:'Off' dweetAge:'11'=1734166927-1734166916
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (1) found /srv/www/viehai/shelly//meter/ping_SP_1.DEAD and Off//srv/www/viehai/shelly//meter/BTscan_BTid_1.No
Dec 14 10:02:07 srv4hai common.sh: SHELLY: BTstatus[2]:'null' dweetAge:'11'=1734166927-1734166916
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (2) found /srv/www/viehai/shelly//meter/ping_SP_2.DEAD and null//srv/www/viehai/shelly//meter/BTscan_BTid_2.No
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (bt_scan)-(0) what: 'presence' - persPresent: '(__/LIVE) (__/__) (__/__)' - anyPresent: 'true' - otherPresent:'false'
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY - check 'presence': anyPresent(true) - lScene(scene_buero_UM) - sceneOFF(offAll)
Dec 14 10:02:07 srv4hai check_presence[26971]: SHELLY (bt_scan)-(0)-presence: NOT activate_scenario.shtml: offAll
Dec 14 10:02:13 srv4hai named[30406]: resolver priming query complete
Dec 14 10:02:45 srv4hai named[30406]: resolver priming query complete
Dec 14 10:02:50 srv4hai postfix/anvil[25821]: statistics: max connection rate 1/60s for (smtp:80.94.95.239) at Dec 14 09:59:26
Dec 14 10:02:50 srv4hai postfix/anvil[25821]: statistics: max connection count 1 for (smtp:80.94.95.239) at Dec 14 09:59:26
Dec 14 10:02:50 srv4hai postfix/anvil[25821]: statistics: max cache size 1 at Dec 14 09:59:26
Dec 14 10:02:56 srv4hai fetchmail[1651]: 7 messages (7 seen) for rozmig at mail.akis.at (1753652 octets).
Dec 14 10:02:57 srv4hai fetchmail[1651]: 59274 messages (59273 seen) for tmiglinci at mail.akis.at (577232290 octets).
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: connect from localhost[127.0.0.1]
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: 26031631BE: client=localhost[127.0.0.1]
Dec 14 10:02:57 srv4hai postfix/cleanup[27135]: 26031631BE: message-id=<0.0.36.EFB.1DB4E06C0361BBC.0@uspmta120020.emarsys.net>
Dec 14 10:02:57 srv4hai fetchmail[1651]: reading message tmiglinci@mail.akis.at:59274 of 59274 (92465 octets) not flushed
Dec 14 10:02:57 srv4hai postfix/qmgr[8566]: 26031631BE: from=, size=92750, nrcpt=1 (queue active)
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Dec 14 10:02:57 srv4hai postfix/local[27136]: 26031631BE: to=, relay=local, delay=0.35, delays=0.31/0.02/0/0.02, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Dec 14 10:02:57 srv4hai postfix/qmgr[8566]: 26031631BE: removed
Dec 14 10:02:57 srv4hai fetchmail[1651]: 11225 messages (11224 seen) for srozum at mail.akis.at (1346378769 octets).
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: connect from localhost[127.0.0.1]
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: D275B631BE: client=localhost[127.0.0.1]
Dec 14 10:02:57 srv4hai postfix/cleanup[27135]: D275B631BE: message-id=
Dec 14 10:02:57 srv4hai fetchmail[1651]: reading message srozum@mail.akis.at:11225 of 11225 (130955 octets) not flushed
Dec 14 10:02:57 srv4hai postfix/qmgr[8566]: D275B631BE: from=, size=131194, nrcpt=1 (queue active)
Dec 14 10:02:57 srv4hai postfix/smtpd[27132]: disconnect from localhost[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Dec 14 10:02:57 srv4hai postfix/local[27136]: D275B631BE: to=, relay=local, delay=0.09, delays=0.08/0/0/0.01, dsn=2.0.0, status=sent (delivered to command: /bin/procmail)
Dec 14 10:02:57 srv4hai postfix/qmgr[8566]: D275B631BE: removed
Dec 14 10:02:58 srv4hai fetchmail[1651]: 27838 messages (27838 seen) for mrozum at mail.akis.at (486414233 octets).
Dec 14 10:02:59 srv4hai fetchmail[1651]: 1159 messages (1159 seen) for admin@bastelstube.wien at pop3.world4you.com (52182817 octets).
Dec 14 10:03:01 srv4hai CRON[27146]: (root) CMD (/etc/linetest/linetest.sh)
Dec 14 10:03:09 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1734166989 - 'ping_SP_0'
Dec 14 10:03:09 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 14 10:03:20 srv4hai named[30406]: resolver priming query complete
Dec 14 10:03:21 srv4hai postfix/smtpd[27132]: connect from unknown[80.94.95.239]
Dec 14 10:03:25 srv4hai postfix/smtpd[27132]: warning: unknown[80.94.95.239]: SASL LOGIN authentication failed: Invalid authentication mechanism
Dec 14 10:03:25 srv4hai postfix/smtpd[27132]: disconnect from unknown[80.94.95.239] ehlo=1 auth=0/1 rset=1 quit=1 commands=3/4
Dec 14 10:03:31 srv4hai dhcpd[4995]: DHCPDISCOVER from 44:17:93:a5:8a:e4 via enp2s0
Dec 14 10:03:31 srv4hai dhcpd[4995]: DHCPOFFER on 192.168.12.147 to 44:17:93:a5:8a:e4 via enp2s0
Dec 14 10:03:31 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/json.sh
Dec 14 10:03:31 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.147
Dec 14 10:03:31 srv4hai dhcpd[4995]: execute_statement argv[2] = shelly-47
Dec 14 10:03:31 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.147 (192.168.12.252) from 44:17:93:a5:8a:e4 via enp2s0
Dec 14 10:03:31 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.147 to 44:17:93:a5:8a:e4 via enp2s0
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f93100debd0 192.168.12.252#36135/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: shelly-47.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f934c09fdf0 192.168.12.252#40345/key rndc-key: signer "rndc-key" approved
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f934c09fdf0 192.168.12.252#40345/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-47.viehai.at' TXT
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f934c09fdf0 192.168.12.252#40345/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-47.viehai.at' TXT "31f97534415d36e3a13ec57b0310c0867f"
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f934c09fdf0 192.168.12.252#40345/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'shelly-47.viehai.at' A
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f934c09fdf0 192.168.12.252#40345/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'shelly-47.viehai.at' A 192.168.12.147
Dec 14 10:03:31 srv4hai dhcpd[4995]: Added new forward map from shelly-47.viehai.at to 192.168.12.147
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45321/key rndc-key: signer "rndc-key" approved
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45321/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '147.12.168.192.in-addr.arpa' PTR
Dec 14 10:03:31 srv4hai named[30406]: client @0x7f932c0c3e90 192.168.12.252#45321/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '147.12.168.192.in-addr.arpa' PTR shelly-47.viehai.at.
Dec 14 10:03:31 srv4hai dhcpd[4995]: Added reverse map from 147.12.168.192.in-addr.arpa. to shelly-47.viehai.at
Dec 14 10:03:31 srv4hai common[27171]: SHELLY common.sh inkludiert von /srv/www/viehai/shelly/grab_shelly.sh
Dec 14 10:03:31 srv4hai grab_shelly.sh: SHELLY shellyName/1:'shelly-47' - shIP/2:'' perform:'conf'
Dec 14 10:03:32 srv4hai mosquitto[29419]: New connection from 192.168.12.147 on port 1883.
Dec 14 10:03:32 srv4hai mosquitto[29419]: Client shellyplus1-441793a58ae4 already connected, closing old connection.
Dec 14 10:03:32 srv4hai mosquitto[29419]: Socket error on client shellyplus1-441793a58ae4, disconnecting.
Dec 14 10:03:32 srv4hai mosquitto[29419]: New client connected from 192.168.12.147 as shellyplus1-441793a58ae4 (c1, k60, u'shmqtt').
Dec 14 10:03:32 srv4hai conf_shelly.sh: SHELLY: PARAMS  shName/1:'shelly-47' - shIP/2:'192.168.12.147'
Dec 14 10:03:32 srv4hai conf_shelly.sh: SHELLY: usage/name 'GB Schreibtischlampe links' already set for '192.168.12.147'
Dec 14 10:03:32 srv4hai conf_shelly.sh: SHELLY:'shelly-47' 'conf(0)' - allready transmitted - skipping
Dec 14 10:03:42 srv4hai named[30406]: resolver priming query complete
Dec 14 10:04:01 srv4hai CRON[27242]: (root) CMD (/etc/linetest/linetest.sh)
Dec 14 10:04:04 srv4hai dovecot: imap-login: Login: user=, method=PLAIN, rip=::1, lip=::1, mpid=27250, secured, session=
Dec 14 10:04:04 srv4hai dovecot: imap(miglincit)<27250>: Logged out in=1668 out=159308 deleted=0 expunged=0 trashed=0 hdr_count=246 hdr_bytes=87415 body_count=0 body_bytes=0
Dec 14 10:04:22 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1734167062 - 'ping_SP_0'
Dec 14 10:04:22 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 14 10:04:49 srv4hai named[30406]: resolver priming query complete
Dec 14 10:05:01 srv4hai CRON[27284]: (root) CMD (/etc/linetest/linetest.sh)
Dec 14 10:05:33 srv4hai named[30406]: resolver priming query complete
Dec 14 10:05:35 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 1 sucessfully  @~1734167135 - 'ping_SP_0'
Dec 14 10:05:35 srv4hai ping_SP[11650]: SHELLY (0) - ping 192.168.12.61 pCnt:'1'
Dec 14 10:05:37 srv4hai dhcpd[4995]: execute_statement argv[0] = /srv/www/viehai/shelly/dhcpcommit.sh
Dec 14 10:05:37 srv4hai dhcpd[4995]: execute_statement argv[1] = 192.168.12.10
Dec 14 10:05:37 srv4hai dhcpd[4995]: execute_statement argv[2] = pchai7x
Dec 14 10:05:37 srv4hai dhcpd[4995]: DHCPREQUEST for 192.168.12.10 from 70:54:d2:7b:fe:a7 via enp2s0
Dec 14 10:05:37 srv4hai dhcpd[4995]: DHCPACK on 192.168.12.10 to 70:54:d2:7b:fe:a7 via enp2s0
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f9350100b10 192.168.12.252#55303/key rndc-key: updating zone 'viehai.at/IN': update unsuccessful: pchai7x.viehai.at: 'name not in use' prerequisite not satisfied (YXDOMAIN)
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#56945/key rndc-key: signer "rndc-key" approved
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#56945/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' TXT
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#56945/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' TXT "001e3ad86ca86e10b8e2aaf2e2ade54f52"
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#56945/key rndc-key: updating zone 'viehai.at/IN': deleting rrset at 'pchai7x.viehai.at' A
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93300a3990 192.168.12.252#56945/key rndc-key: updating zone 'viehai.at/IN': adding an RR at 'pchai7x.viehai.at' A 192.168.12.10
Dec 14 10:05:37 srv4hai dhcpd[4995]: Added new forward map from pchai7x.viehai.at to 192.168.12.10
Dec 14 10:05:37 srv4hai dhcpcommit.sh: SHELLY ip:'192.168.12.10'(10) device 'pchai7x'
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#59901/key rndc-key: signer "rndc-key" approved
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#59901/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': deleting rrset at '10.12.168.192.in-addr.arpa' PTR
Dec 14 10:05:37 srv4hai named[30406]: client @0x7f93506c16d0 192.168.12.252#59901/key rndc-key: updating zone '12.168.192.in-addr.arpa/IN': adding an RR at '10.12.168.192.in-addr.arpa' PTR pchai7x.viehai.at.
Dec 14 10:05:37 srv4hai dhcpd[4995]: Added reverse map from 10.12.168.192.in-addr.arpa. to pchai7x.viehai.at
Dec 14 10:05:37 srv4hai activate_scenario[27321]: SHELLY scenario: 'pcON(pcon)' started
Dec 14 10:05:37 srv4hai activate_scenario[27321]: SHELLY dayState='day' <--> DayTime=checked NightTime=checked - origin:'dhcpcommit.sh-pchai7x'

finished