CCU2 Auslastung 100%

scotty406 · Beitrag von **scotty406** » 06.01.2018, 08:49

Hallo zusammen
Ich habe in letzter Zeit oft eine CCU2 Auslastung von 100%

Folgende Infos wird mir im CUxD SysLog angezeigt ???

Code: Alles auswählen

Jan  6 08:34:02 homematic-ccu2 user.info kernel: [248687.100000] eq3loop: eq3loop_write_master() retrun error:
Jan  6 08:34:02 homematic-ccu2 user.err kernel: [248688.020000] eq3loop: eq3loop_write_master() ttyS0: not enought space in the buffers. free space = 26, required space = 48

Wie kann ich den Fehler weiter eingränzen?

Nach Power aus / ein pendelt sich die CCU2 so bei 6-25% ein ?

Gruß Scotty

anli · Beitrag von **anli** » 06.01.2018, 09:29

Hallo,

du kannst einen SSH-Zugang konfigurieren und über die Konsole

Code: Alles auswählen

top

und

Code: Alles auswählen

ps -ef

ausgeben lassen. Wenn du die Ausgaben beider Befehle hier in Code-Tags postest können wir es weiter analysieren.

scotty406 · Beitrag von **scotty406** » 06.01.2018, 09:48

Hallo Anli
Danke für die Unterstützung.
Anbei die gewünschten Log-Files.

Gruß Scotty

PS. Die CCU wurde inzwischen aus/eingeschaltet

NickHM · Beitrag von **NickHM** » 06.01.2018, 12:10

anli hat geschrieben: Wenn du die Ausgaben beider Befehle hier in Code-Tags postest können wir es weiter analysieren.

Guten Morgen Scotty

es gibt so Kleinigkeiten, mit denen sich User im Forum "beliebt" machen und den Helfenden das Leben erschweren. Dazu zählt z.b. das Runterladen von Textdateien (auf das Handy oder Tablet) um sich Deine Log Files anzusehen.
Über dem Editorfenster (wenn Du Beiträge mit einem Browser schreibst) hast Du einen Button "Code"
Der erzeugt zwei Marker zwischen die Du Deine Log Files einfügen kannst
[co de] hier mein Log File [/co de]
Ohne Browser kannst Du die Marker auch manuell zufügen, natürlich ohne das Leerzeichen in der Mitte.

Danke

scotty406 · Beitrag von **scotty406** » 06.01.2018, 16:02

Hallo NickHM
Man(n) lernt ja nie aus.
Danke für den Hinweis.

Gruß Scotty

anli · Beitrag von **anli** » 06.01.2018, 16:42

Hallo Scotty,

momentan ist alles ok, logisch, da du neu gestartet hast. Beobachte es mal über die Zeit und melde dich, wenn der via top beobachtete freie Speicher knapp wird.

scotty406 · Beitrag von **scotty406** » 12.01.2018, 08:11

Hallo zusammen
Heute hat meine CCU wieder 100%

Anbei die Auswertungen lt. Anli

Danke für Eure Hilfe.
Da zZ bei mir recht wenig funktioniert, werde ich jetzt mal wieder Power Off/On an der CCU durchführen.

Gruß Scotty

Code: Alles auswählen

[H[JMem: 233184K used, 22208K free, 0K shrd, 7856K buff, 73304K cached
CPU:  94% usr   5% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.20 1.30 1.23 2/136 4538
[7m  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND[0m
  443     1 root     S     168m  67%  76% java -Xmx64m -Dlog4j.configuration=fil
 4538  4537 root     R     2380   1%  18% top
  528     1 root     S     3968   2%   6% /bin/hss_led
  526     1 root     S    31148  12%   0% /bin/ReGaHss.normal -f /etc/rega.conf
  392     1 root     S    30324  12%   0% /bin/rfd -f /etc/config/rfd.conf -l 5
  370     1 root     S     5420   2%   0% /bin/multimacd -f /etc/config/multimac
  249     1 root     S     5208   2%   0% /usr/sbin/lighttpd -f /etc/lighttpd/li
 4522   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
 4512   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
  267     1 root     S     4288   2%   0% /usr/sbin/sshd
  233     1 root     S     3736   1%   0% /bin/eq3configd
  563     1 root     S     3420   1%   0% /usr/local/addons/cuxd/cuxd
  118     1 root     S     2776   1%   0% /lib/udev/udevd -d
  155   118 root     S     2772   1%   0% /lib/udev/udevd -d
  156   118 root     S     2772   1%   0% /lib/udev/udevd -d
  113     1 root     S     2648   1%   0% /sbin/syslogd -m 0 -R /media/sd-mmcblk
  109     1 root     S     2464   1%   0% /usr/sbin/crond
  620     1 root     S     2384   1%   0% /sbin/getty -L ttyAMA0 115200 vt100
  621     1 root     S     2384   1%   0% /sbin/getty -L ttyGS0 115200 vt100
    1     0 root     S     2380   1%   0% init
[H[JMem: 233244K used, 22148K free, 0K shrd, 7856K buff, 73304K cached
CPU:  99% usr   0% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.18 1.30 1.23 2/136 4538
[7m  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND[0m
  443     1 root     S     168m  67%  99% java -Xmx64m -Dlog4j.configuration=fil
 4538  4537 root     R     2468   1%   1% top
  528     1 root     S     3968   2%   1% /bin/hss_led
  526     1 root     S    31148  12%   0% /bin/ReGaHss.normal -f /etc/rega.conf
  392     1 root     S    30324  12%   0% /bin/rfd -f /etc/config/rfd.conf -l 5
  370     1 root     S     5420   2%   0% /bin/multimacd -f /etc/config/multimac
  249     1 root     S     5208   2%   0% /usr/sbin/lighttpd -f /etc/lighttpd/li
 4522   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
 4512   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
  267     1 root     S     4288   2%   0% /usr/sbin/sshd
  233     1 root     S     3736   1%   0% /bin/eq3configd
  563     1 root     S     3420   1%   0% /usr/local/addons/cuxd/cuxd
  118     1 root     S     2776   1%   0% /lib/udev/udevd -d
  155   118 root     S     2772   1%   0% /lib/udev/udevd -d
  156   118 root     S     2772   1%   0% /lib/udev/udevd -d
  113     1 root     S     2648   1%   0% /sbin/syslogd -m 0 -R /media/sd-mmcblk
  109     1 root     S     2464   1%   0% /usr/sbin/crond
  620     1 root     S     2384   1%   0% /sbin/getty -L ttyAMA0 115200 vt100
  621     1 root     S     2384   1%   0% /sbin/getty -L ttyGS0 115200 vt100
    1     0 root     S     2380   1%   0% init
[H[JMem: 233244K used, 22148K free, 0K shrd, 7856K buff, 73304K cached
CPU:  98% usr   1% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.17 1.29 1.22 2/136 4538
[7m  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND[0m
  443     1 root     S     168m  67%  95% java -Xmx64m -Dlog4j.configuration=fil
  526     1 root     S    31148  12%   2% /bin/ReGaHss.normal -f /etc/rega.conf
  392     1 root     S    30324  12%   1% /bin/rfd -f /etc/config/rfd.conf -l 5
 4538  4537 root     R     2468   1%   1% top
  528     1 root     S     3968   2%   0% /bin/hss_led
  563     1 root     S     3420   1%   0% /usr/local/addons/cuxd/cuxd
  370     1 root     S     5420   2%   0% /bin/multimacd -f /etc/config/multimac
    6     2 root     SW       0   0%   0% [rcu_kthread]
  249     1 root     S     5208   2%   0% /usr/sbin/lighttpd -f /etc/lighttpd/li
 4522   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
 4512   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
  267     1 root     S     4288   2%   0% /usr/sbin/sshd
  233     1 root     S     3736   1%   0% /bin/eq3configd
  118     1 root     S     2776   1%   0% /lib/udev/udevd -d
  155   118 root     S     2772   1%   0% /lib/udev/udevd -d
  156   118 root     S     2772   1%   0% /lib/udev/udevd -d
  113     1 root     S     2648   1%   0% /sbin/syslogd -m 0 -R /media/sd-mmcblk
  109     1 root     S     2464   1%   0% /usr/sbin/crond
  620     1 root     S     2384   1%   0% /sbin/getty -L ttyAMA0 115200 vt100
  621     1 root     S     2384   1%   0% /sbin/getty -L ttyGS0 115200 vt100
[H[JMem: 233244K used, 22148K free, 0K shrd, 7856K buff, 73304K cached
CPU:  98% usr   1% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.15 1.29 1.22 2/136 4538
[7m  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND[0m
  443     1 root     S     168m  67%  94% java -Xmx64m -Dlog4j.configuration=fil
  526     1 root     S    31148  12%   2% /bin/ReGaHss.normal -f /etc/rega.conf
  392     1 root     S    30324  12%   1% /bin/rfd -f /etc/config/rfd.conf -l 5
  528     1 root     S     3968   2%   1% /bin/hss_led
 4538  4537 root     R     2468   1%   1% top
  370     1 root     S     5420   2%   0% /bin/multimacd -f /etc/config/multimac
  563     1 root     S     3420   1%   0% /usr/local/addons/cuxd/cuxd
  161     2 root     SW       0   0%   0% [mmcqd/0]
  249     1 root     S     5208   2%   0% /usr/sbin/lighttpd -f /etc/lighttpd/li
 4522   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
 4512   249 root     S     4448   2%   0% /bin/tclsh /www/api/homematic.cgi
  267     1 root     S     4288   2%   0% /usr/sbin/sshd
  233     1 root     S     3736   1%   0% /bin/eq3configd
  118     1 root     S     2776   1%   0% /lib/udev/udevd -d
  155   118 root     S     2772   1%   0% /lib/udev/udevd -d
  156   118 root     S     2772   1%   0% /lib/udev/udevd -d
  113     1 root     S     2648   1%   0% /sbin/syslogd -m 0 -R /media/sd-mmcblk
  109     1 root     S     2464   1%   0% /usr/sbin/crond
  620     1 root     S     2384   1%   0% /sbin/getty -L ttyAMA0 115200 vt100
  621     1 root     S     2384   1%   0% /sbin/getty -L ttyGS0 115200 vt100
[H[JMem: 233244K used, 22148K free, 0K shrd, 7856K buff, 73304K cached
CPU:  98% usr   0% sys   0% nic   0% idle   0% io   0% irq   0% sirq
Load average: 1.14 1.28 1.22 3/136 4538
[7m  PID  PPID USER     STAT   VSZ %VSZ %CPU COMMAND[0m
  443     1 root     S     168m  67%  98% java -Xmx64m -Dlog4j.configuration=fil
 4538  4537 root     R     2468   1%   1% top
  528     1 root     R     3976   2%   0% /bin/hss_led
  392     1 root     S    30324  12%   0% /bin/rfd -f /etc/config/rfd.conf -l 5

Code: Alles auswählen

PID   USER     COMMAND
    1 root     init
    2 root     [kthreadd]
    3 root     [ksoftirqd/0]
    6 root     [rcu_kthread]
    7 root     [khelper]
    8 root     [kdevtmpfs]
    9 root     [netns]
   10 root     [sync_supers]
   11 root     [bdi-default]
   12 root     [kintegrityd]
   13 root     [kblockd]
   14 root     [rpciod]
   15 root     [kworker/0:1]
   16 root     [kswapd0]
   17 root     [fsnotify_mark]
   18 root     [nfsiod]
   19 root     [crypto]
   24 root     [mtdblock0]
   25 root     [mtdblock1]
   26 root     [mtdblock2]
   27 root     [mtdblock3]
   28 root     [mtdblock4]
   29 root     [mtdblock5]
   30 root     [mtdblock6]
   31 root     [mtdblock7]
   32 root     [ubi_bgt0d]
   33 root     [ubi_bgt1d]
   34 root     [kworker/u:1]
   37 root     [deferwq]
   38 root     [devfreq_wq]
   43 root     [ubifs_bgt1_0]
   81 root     [eq3spi.0]
   88 root     [file-storage]
   91 root     [khubd]
  109 root     /usr/sbin/crond
  113 root     /sbin/syslogd -m 0 -R /media/sd-mmcblk0/logs -L
  115 root     /sbin/klogd
  118 root     /lib/udev/udevd -d
  129 root     watchdog -t 30 /dev/watchdog
  136 root     [kworker/u:2]
  141 root     udhcpd -S /etc/udhcpd.usb0.conf
  155 root     /lib/udev/udevd -d
  156 root     /lib/udev/udevd -d
  161 root     [mmcqd/0]
  166 root     [scsi_eh_0]
  167 root     [usb-storage]
  209 root     /usr/sbin/ifplugd -i eth0 -fI -u0 -d10
  233 root     /bin/eq3configd
  249 root     /usr/sbin/lighttpd -f /etc/lighttpd/lighttpd.conf
  267 root     /usr/sbin/sshd
  370 root     /bin/multimacd -f /etc/config/multimacd.conf -l 5
  392 root     /bin/rfd -f /etc/config/rfd.conf -l 5
  443 root     java -Xmx64m -Dlog4j.configuration=file:///etc/config/log4j.xml -Dfile.encoding=ISO-8859-1 -jar /opt/HMServer/HMIPServer.jar /etc/crRFD.conf
  526 root     /bin/ReGaHss.normal -f /etc/rega.conf -l 2
  528 root     /bin/hss_led
  563 root     /usr/local/addons/cuxd/cuxd
  620 root     /sbin/getty -L ttyAMA0 115200 vt100
  621 root     /sbin/getty -L ttyGS0 115200 vt100
  624 root     [flush-179:0]
 2360 root     [kworker/0:0]
 4511 root     /bin/tclsh /www/api/homematic.cgi
 4512 root     /bin/tclsh /www/api/homematic.cgi
 4522 root     /bin/tclsh /www/api/homematic.cgi
 4530 root     /www/addons/cuxd/index.ccc
 4531 root     sh -c top 2>&1
 4532 root     top
 4533 root     /www/addons/cuxd/index.ccc
 4534 root     sh -c ps -ef 2>&1
 4535 root     ps -ef

anli · Beitrag von **anli** » 13.01.2018, 13:48

Hallo,
da der java-Prozeß die CCU2 stark auslastet müssen wir in der Datei /var/log/hmserver.log schauen was da den Prozeß so stark auslastet. Kannst Du diese Datei vor dem nächsten Reboot einmal sichern und hier posten?

scotty406 · Beitrag von **scotty406** » 14.01.2018, 23:19

Hallo Anli
CCU hat wieder 100%

Anbei die Logdatei

Code: Alles auswählen

Jan 13 19:07:49 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] @GenericFactory 
Jan 13 19:07:49 de.eq3.lib.util.dynamics.GenericFactory INFO  [main] creating instance of HMServerConfiguration with no-arg constructor 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [HMIPTRXWriterWorker] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KeyServerWorker] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [KryoPersistenceWorker] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [TransactionSubsystemHandler] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [FirmwareLoaderFileSystem] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerPersistentDataLoader] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerAdapterInitialization] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [BackendCommandHandler] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionAcceptHandler] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [IncomingHMIPFrameHandler] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceBackgroundUpdateSubsystem] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [DeviceInclusionDefaultConfigurationChanger] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [CyclicSmokeDetectorAwakening] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LocalServerFirmwareUpdateInitialization] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyNotificationHandler] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyAPIWorker] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBackendNotificationHandler] (3) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyBlindLevelCorrectionHandler] (1) *worker 
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: added for deployment [LegacyInitializion] (1)  
Jan 13 19:08:39 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: deploying 19 classes to Vert.x 
Jan 13 19:08:40 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: 19 VertxDeployers initialized 
Jan 13 19:08:44 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of TransactionSubsystemHandler succeeded (0cddc614-7923-4047-a6f6-56ae5360c10d) 
Jan 13 19:08:44 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of CyclicSmokeDetectorAwakening succeeded (02d2c413-69fd-4b00-a544-8e7f88f2c6a6) 
Jan 13 19:08:44 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceInclusionDefaultConfigurationChanger succeeded (ef6743a1-8258-419e-a69f-b3a44d4c0676) 
Jan 13 19:08:44 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of HMIPTRXWriterWorker succeeded (bfb137be-1799-4af0-9c56-16015b11fec6) 
Jan 13 19:08:45 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of LegacyBlindLevelCorrectionHandler succeeded (315f6f16-347e-42de-84bd-a8e600a326ae) 
Jan 13 19:08:45 de.eq3.cbcs.server.core.vertx.KeyServerWorker ERROR [vert.x-worker-thread-2] Missing key server configuration parameter (Network.Key) for  mode: KEYSERVER_LOCAL 
Jan 13 19:08:45 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of KeyServerWorker succeeded (65d23418-06e6-4727-ac30-b2c451202723) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerPersistentDataLoader still not met - check deployment configuration  
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerAdapterInitialization still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of KryoPersistenceWorker still not met - check deployment configuration  
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of FirmwareLoaderFileSystem still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of DeviceInclusionAcceptHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of DeviceBackgroundUpdateSubsystem still not met - check deployment configuration  
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration  (still unfulfilled: [connector.open][firmware.update.subsystem.started][persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of IncomingHMIPFrameHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyAPIWorker still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of LegacyNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration  (still unfulfilled: [connector.open][persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of BackendCommandHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:47 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyBackendNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:53 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: long-lasting deployment, check deployment configuration - currently deployed 6 out of 19 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerPersistentDataLoader still not met - check deployment configuration  
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of FirmwareLoaderFileSystem still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerAdapterInitialization still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of KryoPersistenceWorker still not met - check deployment configuration  
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of DeviceInclusionAcceptHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of DeviceBackgroundUpdateSubsystem still not met - check deployment configuration  
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration  (still unfulfilled: [connector.open][firmware.update.subsystem.started][persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of IncomingHMIPFrameHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyAPIWorker still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of LegacyNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration  (still unfulfilled: [connector.open][persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of BackendCommandHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:54 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyBackendNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:08:57 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceBackgroundUpdateSubsystem succeeded (3b35df69-c473-4b88-b3e8-7778bd32d659) 
Jan 13 19:08:59 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of KryoPersistenceWorker succeeded (712de23a-1eb4-4533-bfae-49349994f117) 
Jan 13 19:08:59 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LocalServerPersistentDataLoader succeeded (fe403b2e-0d35-4c16-bb44-1c8d92ae6732) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of FirmwareLoaderFileSystem still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerAdapterInitialization still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of DeviceInclusionAcceptHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration  (still unfulfilled: [connector.open][persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of IncomingHMIPFrameHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyAPIWorker still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of LegacyNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration  (still unfulfilled: [connector.open][persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: pre-conditions for deployment of BackendCommandHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:01 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyBackendNotificationHandler still not met - check deployment configuration  (still unfulfilled: [persistent.data.loaded]) 
Jan 13 19:09:03 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DeviceInclusionAcceptHandler succeeded (fca7ec64-d9ae-4676-8d1d-b5ff5f4614b4) 
Jan 13 19:09:03 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of IncomingHMIPFrameHandler succeeded (6f76c1ff-fb35-44eb-98eb-4a0367c6611b) 
Jan 13 19:09:03 de.eq3.cbcs.server.core.otau.util.FirmwareLoaderFileSystem INFO  [vert.x-worker-thread-2] SYSTEM: Firmware update directory is set to /etc/config/firmware 
Jan 13 19:09:03 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of FirmwareLoaderFileSystem succeeded (424b62cd-8fcf-4943-a4d5-10de35a592be) 
Jan 13 19:09:04 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyBackendNotificationHandler succeeded (ef761a41-0103-4725-ad94-955a67479ca4) 
Jan 13 19:09:04 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of BackendCommandHandler succeeded (bf3cc90c-aadf-46c4-96e6-fc01a0522b49) 
Jan 13 19:09:05 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyNotificationHandler succeeded (809d1b28-712f-40b5-9ba4-461cc4c6cc32) 
Jan 13 19:09:06 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LegacyAPIWorker succeeded (bbccf557-8685-40de-9f32-bb6872c5f820) 
Jan 13 19:09:06 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of LocalServerAdapterInitialization succeeded (e07ae8ca-3d18-44d2-8958-e88a61b0c324) 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter state 1: HMIP_TRX_App 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter application is already running 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] No NWK, try to set address ... 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to set radio address 11882256... 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set max send attempts for 3014F711A0000352298D2DE7 to 3 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Try to get application version... 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Application version 2.8.6 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Bootloader version 1.0.5 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] hmos version 1.20.3 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] MCU type: ATmega644pa_AVR 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Duty Cycle: 2.0 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] set DutyCycle limit to ffffffc8 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Set Duty Cycle Limit 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Current Security Counter: 196034051 
Jan 13 19:09:07 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Update security counter to calculation: 196450492 
Jan 13 19:09:07 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-0] SYSTEM ADVICE: long-lasting deployment, check deployment configuration - currently deployed 17 out of 19 
Jan 13 19:09:08 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LocalServerFirmwareUpdateInitialization still not met - check deployment configuration  (still unfulfilled: [connector.open]) 
Jan 13 19:09:08 de.eq3.cbcs.vertx.management.VertxManager WARN  [vert.x-eventloop-thread-1] SYSTEM ADVICE: pre-conditions for deployment of LegacyInitializion still not met - check deployment configuration  (still unfulfilled: [connector.open]) 
Jan 13 19:09:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] TRX adapter has 5 link partners 
Jan 13 19:09:09 de.eq3.cbcs.server.local.base.internal.HMIPTRXInitialResponseListener INFO  [Thread-6] Adapter with Access Point id 3014F711A0000352298D2DE7 initialized 
Jan 13 19:09:10 de.eq3.cbcs.server.local.base.internal.LocalServerAdapterInitialization INFO  [Thread-6] HMIPTRXInitialResponseListener said that Adapter was initialized 
Jan 13 19:09:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of LocalServerFirmwareUpdateInitialization succeeded (7529a202-0176-482d-8f4c-c67f32d34a1b) 
Jan 13 19:09:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of LegacyInitializion succeeded (339ffba1-69d4-40c7-8041-a8d328560bd6) 
Jan 13 19:09:10 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-0] SYSTEM: initial deployment complete _____________________________________________________ 
Jan 13 19:09:11 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-0] SYSTEM: Checking all devices on all accesspoints for updates 
Jan 13 19:09:11 de.eq3.cbcs.server.core.otau.DeviceBackgroundUpdateSubsystem INFO  [vert.x-eventloop-thread-0] SYSTEM: There are 0 APs queued with updatable devices 
Jan 13 19:09:15 de.eq3.ccu.server.HMServer INFO  [Thread-2] Default MaxEventLoopExecuteTime: 2000000000 
Jan 13 19:09:15 de.eq3.ccu.server.HMServer INFO  [Thread-2] Default BlockedThreadCheckInterval: 1000 
Jan 13 19:09:15 de.eq3.ccu.server.HMServer INFO  [Thread-2] Default MaxWorkerExecuteTime: 60000000000 
Jan 13 19:09:15 de.eq3.ccu.server.HMServer INFO  [Thread-2] Default EventLoopPoolSize: 2 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [BackendWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [GroupRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DiagramRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [StorageRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [DeviceFirmwareRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [EnergyPriceRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: added for deployment [CouplingRequestWorker] (1) *worker 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: deploying 7 classes to Vert.x 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: 7 VertxDeployers initialized 
Jan 13 19:09:15 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of BackendWorker succeeded (8e1a4d04-9fdd-414b-b63c-37bf32fc3589) 
Jan 13 19:09:16 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-1] SYSTEM: start of CouplingRequestWorker succeeded (3eb18ba2-3e31-46bb-8a9d-092ab8c6d7e3) 
Jan 13 19:09:16 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of EnergyPriceRequestWorker succeeded (2f15c954-e9f6-453b-ad78-0b6ffc123eb6) 
Jan 13 19:09:16 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-5] SYSTEM: start of StorageRequestWorker succeeded (0de14a7f-7747-40e3-a55c-d80fe88f1bf1) 
Jan 13 19:09:20 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-2] SYSTEM: start of GroupRequestWorker succeeded (c8db5566-f53c-4a41-9fe9-728c5bbbc35c) 
Jan 13 19:09:20 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-0] SYSTEM: start of DiagramRequestWorker succeeded (b0b08ddc-31d3-4769-ab38-76d7a9302392) 
Jan 13 19:09:20 de.eq3.cbcs.vertx.management.VertxManager INFO  [vert.x-eventloop-thread-4] SYSTEM: start of DeviceFirmwareRequestWorker succeeded (770ebd87-24c9-4e85-b069-97763b42f40a) 
Jan 13 19:09:20 de.eq3.cbcs.vertx.management.VertxManager INFO  [Thread-2] SYSTEM: initial deployment complete _____________________________________________________ 
Jan 13 19:09:20 de.eq3.ccu.server.HMServer INFO  [Thread-2] Starting HMServer at 127.0.0.1:9292 
Jan 13 19:09:20 de.eq3.ccu.server.HMServer INFO  [Thread-2] Read Configuration 
Jan 13 19:09:27 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create Bidcos Dispatcher 
Jan 13 19:09:29 de.eq3.ccu.server.HMServer INFO  [Thread-2] InitBidCosCache 
Jan 13 19:09:54 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create groupDefinitionProvider 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create VirtualDeviceHolder 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create VirtualDeviceHandlerRega 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create GroupAdministrationService 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create GroupDeviceDispatcher 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create GroupDeviceHandler 
Jan 13 19:10:03 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] @GroupDeviceHandler - initializing... 
Jan 13 19:10:03 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created groupDeviceDispatcher (GroupDeviceService to BidCoS (via Dispatcher)) 
Jan 13 19:10:03 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> created virtualDeviceHandler (GroupDeviceService to ReGa) 
Jan 13 19:10:03 de.eq3.ccu.groupdevice.service.GroupDeviceHandler INFO  [Thread-2] --> got groupDefinitionProvider 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create BidCosGroupMemberProvider 
Jan 13 19:10:03 de.eq3.ccu.server.HMServer INFO  [Thread-2] Init groupAdministrationService 
Jan 13 19:10:05 de.eq3.ccu.server.HMServer INFO  [Thread-2] Init Virtual OS Device 
Jan 13 19:10:06 de.eq3.ccu.server.HMServer INFO  [Thread-2] Init ESHLight Bridge 
Jan 13 19:10:16 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create RrdDatalogging 
Jan 13 19:10:16 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create MeasurementService 
Jan 13 19:10:16 de.eq3.ccu.server.HMServer INFO  [Thread-2] Init MeasurementService 
Jan 13 19:10:18 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create HTTP Server 
Jan 13 19:10:19 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create BidCos context and start handler 
Jan 13 19:10:19 de.eq3.ccu.server.HMServer INFO  [Thread-2] Create group context and start handler 
Jan 13 19:10:19 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-2] (un)registerCallback on LegacyServiceHandler called from url: http://127.0.0.1:9292/bidcos 
Jan 13 19:10:19 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-2] init finished 
Jan 13 19:10:20 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-1] Added InterfaceId: HmIP-RF_java 
Jan 13 19:10:20 de.eq3.ccu.server.HMServer INFO  [Thread-2] Starting HMServer done 
Jan 13 19:10:21 de.eq3.cbcs.legacy.bidcos.rpc.internal.DeviceUtil INFO  [vert.x-worker-thread-1] updateDevicesForClient HmIP-RF_java -> 45 device addresses will be added 
Jan 13 19:12:59 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] (un)registerCallback on VirtualDeviceHandlerRega called from url: xmlrpc_bin://127.0.0.1:1999 
Jan 13 19:12:59 de.eq3.ccu.virtualdevice.service.internal.rega.VirtualDeviceHandlerRega INFO  [vert.x-eventloop-thread-1] Added InterfaceId: 1008 
Jan 13 19:12:59 de.eq3.ccu.virtualdevice.service.internal.rega.BackendWorker INFO  [vert.x-worker-thread-7] Execute BackendCommand: de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand 
Jan 13 19:13:00 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-7] updateDevicesForClient -> 16 device addresses will be added 
Jan 13 19:13:10 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-1] (un)registerCallback on LegacyServiceHandler called from url: xmlrpc_bin://127.0.0.1:1999 
Jan 13 19:13:10 de.eq3.cbcs.legacy.bidcos.rpc.LegacyServiceHandler INFO  [vert.x-worker-thread-1] init finished 
Jan 13 19:13:10 de.eq3.cbcs.legacy.bidcos.rpc.internal.InterfaceInitializer INFO  [vert.x-worker-thread-1] Added InterfaceId: 63595 
Jan 13 19:13:28 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-7] set ready config of INT0000001 
Jan 13 19:13:28 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-7] set ready config of INT0000002 
Jan 13 19:13:29 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-7] set ready config of INT0000003 
Jan 13 19:13:29 de.eq3.ccu.virtualdevice.service.internal.rega.BackendUpdateDevicesCommand INFO  [vert.x-worker-thread-7] set ready config of INT0000004 
Jan 14 14:02:06 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515934926. Last update time was 1515934926, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:02:07 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 14:02:07 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515934926. Last update time was 1515934926, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:02:07 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 14:12:08 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515935528. Last update time was 1515935528, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:12:08 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 14:16:22 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515935782. Last update time was 1515935782, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:16:22 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 14:21:54 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515936114. Last update time was 1515936114, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:21:54 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 14:56:50 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515938210. Last update time was 1515938210, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:56:50 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 14:56:50 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515938210. Last update time was 1515938210, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:56:50 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 14:58:10 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515938290. Last update time was 1515938290, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:58:10 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 14:58:10 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 5 
java.lang.IllegalArgumentException: Bad sample time: 1515938290. Last update time was 1515938290, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 14:58:10 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 5 
Jan 14 15:16:40 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515939400. Last update time was 1515939400, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 15:16:40 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 15:16:40 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515939400. Last update time was 1515939400, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 15:16:40 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 15:28:42 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515940122. Last update time was 1515940122, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 15:28:42 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 15:48:12 de.eq3.lib.measurement.MeasurementService ERROR [Timer-0] Could not retrieve unhandled events 
java.util.ConcurrentModificationException
	at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901)
	at java.util.ArrayList$Itr.next(ArrayList.java:851)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:846)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 16:03:50 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515942230. Last update time was 1515942230, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 16:03:50 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 16:03:50 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515942230. Last update time was 1515942230, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 16:03:50 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 16:11:16 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515942676. Last update time was 1515942676, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 16:11:16 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 16:15:42 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515942942. Last update time was 1515942942, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 16:15:43 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 17:15:24 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515946524. Last update time was 1515946524, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:15:24 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 17:15:24 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515946524. Last update time was 1515946524, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:15:24 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 17:28:54 de.eq3.lib.measurement.MeasurementService ERROR [Timer-0] Could not retrieve unhandled events 
java.util.ConcurrentModificationException
	at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901)
	at java.util.ArrayList$Itr.next(ArrayList.java:851)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:846)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:31:12 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515947472. Last update time was 1515947472, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:31:12 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 17:31:12 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 5 
java.lang.IllegalArgumentException: Bad sample time: 1515947472. Last update time was 1515947472, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:31:12 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 5 
Jan 14 17:31:12 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515947472. Last update time was 1515947472, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:31:12 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 17:43:46 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515948226. Last update time was 1515948226, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:43:46 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 17:43:46 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515948226. Last update time was 1515948226, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 17:43:46 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 18:05:40 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515949540. Last update time was 1515949540, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 18:05:40 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 18:05:40 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515949540. Last update time was 1515949540, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 18:05:40 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 18:16:42 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515950202. Last update time was 1515950202, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 18:16:42 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 18:16:42 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 5 
java.lang.IllegalArgumentException: Bad sample time: 1515950202. Last update time was 1515950202, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 18:16:42 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 5 
Jan 14 18:16:42 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515950202. Last update time was 1515950202, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 18:16:42 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 19:14:55 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515953695. Last update time was 1515953695, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 19:14:55 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 19:16:14 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 7 
java.lang.IllegalArgumentException: Bad sample time: 1515953774. Last update time was 1515953774, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 19:16:14 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 7 
Jan 14 19:32:49 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515954769. Last update time was 1515954769, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 19:32:49 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 19:56:48 de.eq3.lib.measurement.MeasurementService ERROR [Timer-0] Could not retrieve unhandled events 
java.util.ConcurrentModificationException
	at java.util.ArrayList$Itr.checkForComodification(ArrayList.java:901)
	at java.util.ArrayList$Itr.next(ArrayList.java:851)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:846)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 19:58:40 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515956320. Last update time was 1515956320, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 19:58:40 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 20:18:29 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 4 
java.lang.IllegalArgumentException: Bad sample time: 1515957509. Last update time was 1515957509, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:18:29 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 4 
Jan 14 20:18:29 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 8 
java.lang.IllegalArgumentException: Bad sample time: 1515957509. Last update time was 1515957509, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:18:37 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 8 
Jan 14 20:18:37 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 9 
java.lang.IllegalArgumentException: Bad sample time: 1515957509. Last update time was 1515957509, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:18:37 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 9 
Jan 14 20:18:37 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 10 
java.lang.IllegalArgumentException: Bad sample time: 1515957509. Last update time was 1515957509, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:18:37 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 10 
Jan 14 20:18:38 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 11 
java.lang.IllegalArgumentException: Bad sample time: 1515957509. Last update time was 1515957509, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:18:38 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 11 
Jan 14 20:20:50 de.eq3.lib.datalogging.rrd.RrdDatalogging ERROR [Timer-0] The values could not be added to the database 6 
java.lang.IllegalArgumentException: Bad sample time: 1515957650. Last update time was 1515957650, at least one second step is required
	at org.rrd4j.core.RrdDb.store(RrdDb.java:517)
	at org.rrd4j.core.Sample.update(Sample.java:194)
	at de.eq3.lib.datalogging.rrd.RrdDatalogging.logValues(RrdDatalogging.java:335)
	at de.eq3.lib.measurement.MeasurementService.writeLogValues(MeasurementService.java:887)
	at de.eq3.lib.measurement.MeasurementService.access$000(MeasurementService.java:50)
	at de.eq3.lib.measurement.MeasurementService$EventTimerTask.run(MeasurementService.java:121)
	at java.util.TimerThread.mainLoop(Timer.java:555)
	at java.util.TimerThread.run(Timer.java:505)
Jan 14 20:20:58 de.eq3.lib.measurement.MeasurementService INFO  [Timer-0] Drop values (invalid) for diagram 6 
Jan 14 20:37:29 de.eq3.cbcs.lib.nrjavaserialdevice.internal.SerialFrameDecoder ERROR [Thread-6] Frame checksum did not match. Aborting decoding of this frame. 
Jan 14 20:38:53 de.eq3.cbcs.lib.nrjavaserialdevice.internal.SerialFrameDecoder ERROR [Thread-6] Last frame was not complete. Aborting decoding of that frame.

und die CUxD SysLog Datei wg. der Länge im Anhang.

Danke für die Unterstützung
Scotty

anli · Beitrag von **anli** » 14.01.2018, 23:49

Hallo,

es gibt Probleme beim measurement, also bei der Erstellung der Diagramme. Welche hast du angelegt?

HomeMatic-Forum / FHZ-Forum

CCU2 Auslastung 100%

CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%

Re: CCU2 Auslastung 100%