Apr 26 12:55:40.739047 rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="557" x-info="http://www.rsyslog.com"] start Apr 26 12:55:40.743273 systemd[1]: Started Apply Kernel Variables. Apr 26 12:55:40.743290 systemd[1]: Started Create Static Device Nodes in /dev. Apr 26 12:55:40.743296 systemd[1]: Starting udev Kernel Device Manager... Apr 26 12:55:40.743300 systemd[1]: Started udev Kernel Device Manager. Apr 26 12:55:40.743304 systemd[1]: Starting Copy rules generated while the root was ro... Apr 26 12:55:40.743309 systemd[1]: Starting LSB: Set preliminary keymap... Apr 26 12:55:40.743314 systemd[1]: Started Copy rules generated while the root was ro. Apr 26 12:55:40.743320 systemd[1]: Started LSB: Set preliminary keymap. Apr 26 12:55:40.743324 systemd[1]: Starting Show Plymouth Boot Screen... Apr 26 12:55:40.743329 systemd[1]: Starting Remount Root and Kernel File Systems... Apr 26 12:55:40.743333 systemd[1]: Started Remount Root and Kernel File Systems. Apr 26 12:55:40.743338 systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Apr 26 12:55:40.743343 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 26 12:55:40.743347 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Apr 26 12:55:40.743352 systemd[1]: Starting Paths. Apr 26 12:55:40.743358 systemd[1]: Reached target Paths. Apr 26 12:55:40.743363 systemd[1]: Starting system-systemd\x2dbacklight.slice. Apr 26 12:55:40.743367 systemd[1]: Created slice system-systemd\x2dbacklight.slice. Apr 26 12:55:40.743372 systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:acpi_video0... Apr 26 12:55:40.743377 systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:acpi_video0. Apr 26 12:55:40.743382 systemd[1]: Found device CFAST-8G0-XIE82 pack-control. Apr 26 12:55:40.743387 systemd[1]: Mounting /opt... Apr 26 12:55:40.743391 systemd[1]: Found device CFAST-8G0-XIE82 roothome. Apr 26 12:55:40.743400 systemd[1]: Found device CFAST-8G0-XIE82 backup. Apr 26 12:55:40.743404 systemd[1]: Mounting /root... Apr 26 12:55:40.743409 systemd[1]: Mounted /opt. Apr 26 12:55:40.743413 systemd[1]: Mounting /opt/backup... Apr 26 12:55:40.743418 systemd[1]: Mounted /root. Apr 26 12:55:40.743423 systemd[1]: Mounted /opt/backup. Apr 26 12:55:40.743427 systemd[1]: Starting Local File Systems. Apr 26 12:55:40.743432 systemd[1]: Reached target Local File Systems. Apr 26 12:55:40.743436 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 26 12:55:40.743443 systemd[1]: Starting Create Volatile Files and Directories... Apr 26 12:55:40.743447 systemd[1]: Starting Remote File Systems. Apr 26 12:55:40.743452 systemd[1]: Reached target Remote File Systems. Apr 26 12:55:40.743456 systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Apr 26 12:55:40.743461 systemd[1]: Starting LSB: Set console font and keymap... Apr 26 12:55:40.743465 systemd[1]: Starting LSB: Raise network interfaces.... Apr 26 12:55:40.743470 systemd[1]: Started LSB: Set console font and keymap. Apr 26 12:55:40.743475 systemd[1]: Started Create Volatile Files and Directories. Apr 26 12:55:40.743482 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 26 12:55:40.743493 systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Apr 26 12:55:40.743501 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 26 12:55:40.743506 systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 26 12:55:40.743510 systemd[1]: Starting Sound Card. Apr 26 12:55:40.743515 systemd[1]: Reached target Sound Card. Apr 26 12:55:40.743520 dhclient: Internet Systems Consortium DHCP Client 4.3.1 Apr 26 12:55:40.743524 dhclient: Copyright 2004-2014 Internet Systems Consortium. Apr 26 12:55:40.743530 dhclient: All rights reserved. Apr 26 12:55:40.743535 dhclient: For info, please visit https://www.isc.org/software/dhcp/ Apr 26 12:55:40.743540 dhclient: Apr 26 12:55:40.743544 networking[317]: Configuring network interfaces...Internet Systems Consortium DHCP Client 4.3.1 Apr 26 12:55:40.743550 networking[317]: Copyright 2004-2014 Internet Systems Consortium. Apr 26 12:55:40.743554 networking[317]: All rights reserved. Apr 26 12:55:40.743559 networking[317]: For info, please visit https://www.isc.org/software/dhcp/ Apr 26 12:55:40.743563 dhclient: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system Apr 26 12:55:40.743568 networking[317]: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system Apr 26 12:55:40.743575 dhclient: Listening on LPF/eth0/d4:f5:27:e5:48:61 Apr 26 12:55:40.743580 dhclient: Sending on LPF/eth0/d4:f5:27:e5:48:61 Apr 26 12:55:40.743584 dhclient: Sending on Socket/fallback Apr 26 12:55:40.743589 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5 Apr 26 12:55:40.743593 networking[317]: Listening on LPF/eth0/d4:f5:27:e5:48:61 Apr 26 12:55:40.743598 networking[317]: Sending on LPF/eth0/d4:f5:27:e5:48:61 Apr 26 12:55:40.743602 networking[317]: Sending on Socket/fallback Apr 26 12:55:40.743607 networking[317]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5 Apr 26 12:55:40.743613 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 10 Apr 26 12:55:40.743618 networking[317]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 10 Apr 26 12:55:40.743623 dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67 Apr 26 12:55:40.743627 dhclient: DHCPOFFER from 192.168.19.1 Apr 26 12:55:40.743632 networking[317]: DHCPREQUEST on eth0 to 255.255.255.255 port 67 Apr 26 12:55:40.743636 networking[317]: DHCPOFFER from 192.168.19.1 Apr 26 12:55:40.743641 dhclient: DHCPACK from 192.168.19.1 Apr 26 12:55:40.743645 networking[317]: DHCPACK from 192.168.19.1 Apr 26 12:55:40.743652 networking[317]: mktemp: failed to create file via template '/var/lib/ntp/ntp.conf.dhcp.XXXXXX': Read-only file system Apr 26 12:55:40.743656 dhclient: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system Apr 26 12:55:40.743661 dhclient: bound to 192.168.19.108 -- renewal in 359564 seconds. Apr 26 12:55:40.743666 networking[317]: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system Apr 26 12:55:40.743670 networking[317]: bound to 192.168.19.108 -- renewal in 359564 seconds. Apr 26 12:55:40.743675 networking[317]: start exec /usr/bin/setDnsHostname for eth0 from /etc/network/if-up.d/setHostname Apr 26 12:55:40.743679 networking[317]: Cannot find device "eth3" Apr 26 12:55:40.743684 networking[317]: Failed to bring up eth3. Apr 26 12:55:40.743690 networking[317]: done. Apr 26 12:55:40.743695 systemd[1]: Started LSB: Raise network interfaces.. Apr 26 12:55:40.743699 systemd[1]: Starting Network. Apr 26 12:55:40.743704 systemd[1]: Reached target Network. Apr 26 12:55:40.743708 systemd[1]: Starting Network is Online. Apr 26 12:55:40.743713 systemd[1]: Reached target Network is Online. Apr 26 12:55:40.743717 systemd[1]: Starting System Initialization. Apr 26 12:55:40.743722 systemd[1]: Reached target System Initialization. Apr 26 12:55:40.743728 systemd[1]: Starting Daily Cleanup of Temporary Directories. Apr 26 12:55:40.743732 systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 26 12:55:40.743737 systemd[1]: Starting Timers. Apr 26 12:55:40.743741 systemd[1]: Reached target Timers. Apr 26 12:55:40.743746 systemd[1]: Starting Basic System. Apr 26 12:55:40.743750 systemd[1]: Reached target Basic System. Apr 26 12:55:40.743754 systemd[1]: Starting OpenBSD Secure Shell server... Apr 26 12:55:40.743759 systemd[1]: Started OpenBSD Secure Shell server. Apr 26 12:55:40.743765 systemd[1]: Starting Lighttpd Daemon... Apr 26 12:55:40.743769 systemd[1]: Starting Pure-FTPd FTP server... Apr 26 12:55:40.743774 systemd[1]: Starting Regular background program processing daemon... Apr 26 12:55:40.743778 systemd[1]: Started Regular background program processing daemon. Apr 26 12:55:40.743783 systemd[1]: Starting Installation-script for P@ckControl... Apr 26 12:55:40.743787 systemd[1]: Starting Internet superserver... Apr 26 12:55:40.743792 cron[545]: (CRON) INFO (pidfile fd = 3) Apr 26 12:55:40.743796 systemd[1]: Started Internet superserver. Apr 26 12:55:40.743801 systemd[1]: Starting Initialize hardware monitoring sensors... Apr 26 12:55:40.743807 systemd[1]: Starting Pure-FTPd FTP server... Apr 26 12:55:40.743812 cron[545]: (CRON) INFO (Running @reboot jobs) Apr 26 12:55:40.743816 systemd[1]: Starting /etc/rc.local Compatibility... Apr 26 12:55:40.743821 systemd[1]: Starting getty on tty2-tty6 if dbus and logind are not available... Apr 26 12:55:40.743825 systemd[1]: Started Login Service. Apr 26 12:55:40.743830 systemd[1]: Starting LSB: No Display Manager... Apr 26 12:55:40.743834 systemd[1]: Starting LSB: Start NTP daemon... Apr 26 12:55:40.743839 systemd[1]: Starting LSB: Start/stop sysstat's sadc... Apr 26 12:55:40.743845 systemd[1]: Starting System Logging Service... Apr 26 12:55:40.743849 systemd[1]: Starting Permit User Sessions... Apr 26 12:55:40.743854 systemd[1]: PID file /var/run/pure-uploadscript.pid not readable (yet?) after start. Apr 26 12:55:40.742390 sshd[542]: Server listening on 0.0.0.0 port 22. Apr 26 12:55:40.742737 sshd[542]: Server listening on :: port 22. Apr 26 12:55:40.750974 systemd[1]: Started Installation-script for P@ckControl. Apr 26 12:55:40.756090 systemd[1]: PID file /var/run/pure-ftpd.pid not readable (yet?) after start. Apr 26 12:55:40.759258 sensors[577]: coretemp-isa-0000 Apr 26 12:55:40.759516 sensors[577]: Adapter: ISA adapter Apr 26 12:55:40.759724 sensors[577]: Physical id 0: +36.0 C (high = +100.0 C, crit = +100.0 C) Apr 26 12:55:40.759993 sensors[577]: Core 0: +36.0 C (high = +100.0 C, crit = +100.0 C) Apr 26 12:55:40.760201 sensors[577]: Core 1: +35.0 C (high = +100.0 C, crit = +100.0 C) Apr 26 12:55:40.769973 systemd[1]: Started /etc/rc.local Compatibility. Apr 26 12:55:40.770284 systemd[1]: Started LSB: Start/stop sysstat's sadc. Apr 26 12:55:40.770552 systemd[1]: Started Permit User Sessions. Apr 26 12:55:40.772067 systemd[1]: Started Initialize hardware monitoring sensors. Apr 26 12:55:40.774597 systemd[1]: Started System Logging Service. Apr 26 12:55:40.774866 systemd[1]: Received SIGRTMIN+21 from PID 135 (plymouthd). Apr 26 12:55:40.778391 systemd[1]: Started LSB: No Display Manager. Apr 26 12:55:40.779564 nodm[584]: starting nodm Apr 26 12:55:40.789676 ntpd[591]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:48:00 UTC 2016 (1) Apr 26 12:55:40.790578 ntpd[592]: proto: precision = 0.561 usec Apr 26 12:55:40.791290 ntp[555]: Starting NTP server: ntpd. Apr 26 12:55:40.791571 ntpd[592]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Apr 26 12:55:40.792187 systemd[1]: Started LSB: Start NTP daemon. Apr 26 12:55:40.797897 ntpd[592]: Listen and drop on 1 v6wildcard :: UDP 123 Apr 26 12:55:40.798296 ntpd[592]: Listen normally on 2 lo 127.0.0.1 UDP 123 Apr 26 12:55:40.798539 ntpd[592]: Listen normally on 3 eth1 192.168.1.102 UDP 123 Apr 26 12:55:40.798766 ntpd[592]: Listen normally on 4 eth1:1 192.168.1.193 UDP 123 Apr 26 12:55:40.799064 ntpd[592]: Listen normally on 5 eth2 169.254.253.254 UDP 123 Apr 26 12:55:40.799307 ntpd[592]: Listen normally on 6 eth0 192.168.19.108 UDP 123 Apr 26 12:55:40.799525 ntpd[592]: Listen normally on 7 lo ::1 UDP 123 Apr 26 12:55:40.799750 ntpd[592]: Listen normally on 8 eth0 2a03:4b22:6071:5700:d6f5:27ff:fee5:4861 UDP 123 Apr 26 12:55:40.800027 ntpd[592]: Listen normally on 9 eth0 fe80::d6f5:27ff:fee5:4861 UDP 123 Apr 26 12:55:40.800263 ntpd[592]: peers refreshed Apr 26 12:55:40.800477 ntpd[592]: Listening on routing socket on fd #26 for interface updates Apr 26 12:55:40.809309 systemd[1]: Starting Wait for Plymouth Boot Screen to Quit... Apr 26 12:55:40.815885 systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 26 12:55:40.818522 systemd[1]: Starting pcupdate for P@ckControl... Apr 26 12:55:40.820564 lighttpd[543]: Syntax OK Apr 26 12:55:40.822621 systemd[1]: Started Lighttpd Daemon. Apr 26 12:55:40.824303 systemd[1]: Started Wait for Plymouth Boot Screen to Quit. Apr 26 12:55:40.835974 systemd[1]: Started Terminate Plymouth Boot Screen. Apr 26 12:55:40.840250 fortytwo.sh[596]: SIMATIC IPC427E Apr 26 12:55:40.840934 fortytwo.sh[596]: use: /usr/etc/udev/70-persistent-net_427E for Board SIMATIC IPC427E!! Apr 26 12:55:40.845380 fortytwo.sh[596]: mkdir: cannot create directory '/var/log/lighttpd': File exists Apr 26 12:55:40.849017 fortytwo.sh[596]: last boot...succeeded Apr 26 12:55:40.861188 fortytwo.sh[596]: No recalibration is necessary! Apr 26 12:55:40.862034 fortytwo.sh[596]: Calibration file remains untouched. Apr 26 12:55:40.867093 fortytwo.sh[596]: TestHome.RDF filesize seems to be ok - file remains untouched Apr 26 12:55:40.872781 systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Apr 26 12:55:40.874825 systemd[1]: Starting Getty on tty6... Apr 26 12:55:40.879641 fortytwo.sh[596]: check for prepared updates...no updates available Apr 26 12:55:40.880384 systemd[1]: Started Getty on tty6. Apr 26 12:55:40.881121 systemd[1]: Starting Getty on tty5... Apr 26 12:55:40.884484 fortytwo.sh[596]: Check: /opt/update/rovema/lib Apr 26 12:55:40.887841 fortytwo.sh[596]: no files to update available in /opt/update/rovema/lib Apr 26 12:55:40.888314 fortytwo.sh[596]: Check: /opt/update/rovema/rds Apr 26 12:55:40.891491 fortytwo.sh[596]: no files to update available in /opt/update/rovema/rds Apr 26 12:55:40.891910 fortytwo.sh[596]: Check: /opt/update/rovema/plc Apr 26 12:55:40.895135 fortytwo.sh[596]: no files to update available in /opt/update/rovema/plc Apr 26 12:55:40.895566 fortytwo.sh[596]: Check: /opt/update/rovema/adapter Apr 26 12:55:40.898655 fortytwo.sh[596]: no files to update available in /opt/update/rovema/adapter Apr 26 12:55:40.899095 fortytwo.sh[596]: Check: /opt/update/rovema/calcmod Apr 26 12:55:40.902215 fortytwo.sh[596]: no files to update available in /opt/update/rovema/calcmod Apr 26 12:55:40.903954 systemd[1]: Started Getty on tty5. Apr 26 12:55:40.904244 systemd[1]: Starting Getty on tty4... Apr 26 12:55:40.911027 fortytwo.sh[596]: Check: /opt/update/rovema/javis Apr 26 12:55:40.917383 fortytwo.sh[596]: no files to update available in /opt/update/rovema/javis Apr 26 12:55:40.920123 fortytwo.sh[596]: cleaning update folder...done Apr 26 12:55:40.922612 fortytwo.sh[596]: restoring update file structure...done Apr 26 12:55:40.923528 systemd[1]: Started Getty on tty4. Apr 26 12:55:40.923891 systemd[1]: Starting Getty on tty3... Apr 26 12:55:40.928354 systemd[1]: Started Getty on tty3. Apr 26 12:55:40.928619 systemd[1]: Starting Getty on tty2... Apr 26 12:55:40.929154 nodm[584]: X server 590 quit with status 1 Apr 26 12:55:40.930140 systemd[1]: Started Getty on tty2. Apr 26 12:55:40.930503 systemd[1]: Starting Getty on tty1... Apr 26 12:55:40.931987 systemd[1]: Started Getty on tty1. Apr 26 12:55:40.932511 systemd[1]: Starting Login Prompts. Apr 26 12:55:40.932811 systemd[1]: Reached target Login Prompts. Apr 26 12:55:41.682748 systemd[1]: Started Pure-FTPd FTP server. Apr 26 12:55:41.683354 systemd[1]: Started Pure-FTPd FTP server. Apr 26 12:55:41.722632 fortytwo.sh[596]: chown: changing ownership of '/opt/backup/lost+found': Read-only file system Apr 26 12:55:41.723082 fortytwo.sh[596]: chown: changing ownership of '/opt/backup/packcontrol.tar.gz': Read-only file system Apr 26 12:55:41.723337 fortytwo.sh[596]: chown: changing ownership of '/opt/backup': Read-only file system Apr 26 12:55:41.899869 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup': Read-only file system Apr 26 12:55:41.900276 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup/lost+found': Read-only file system Apr 26 12:55:41.900520 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup/packcontrol.tar.gz': Read-only file system Apr 26 12:55:41.905654 fortytwo.sh[596]: done(ext3) Apr 26 12:55:41.906786 systemd[1]: Started pcupdate for P@ckControl. Apr 26 12:55:41.912428 systemd[1]: Starting Start the RDS, REUS and other deamons of P@ckControl... Apr 26 12:55:41.912840 systemd[1]: Starting Adapter (HMI-Adapter)... Apr 26 12:55:41.914716 systemd[1]: Started Adapter (HMI-Adapter). Apr 26 12:55:41.917099 systemd[1]: Starting Synchronize HwClock with system clock... Apr 26 12:55:41.920750 systemd[1]: Started Synchronize HwClock with system clock. Apr 26 12:55:41.928239 synchwclock.sh[667]: unsynchronised Apr 26 12:55:41.928581 synchwclock.sh[667]: time server re-starting Apr 26 12:55:41.928814 synchwclock.sh[667]: polling server every 8 s Apr 26 12:55:41.938272 startadapter.sh[665]: Set NAT-address of 192.168.1.194 to 192.168.108.194. Apr 26 12:56:08.474996 systemd[1]: Time has been changed Apr 26 12:56:08.475849 synchwclock.sh[667]: Tue Apr 26 12:56:08 CEST 2022 Apr 26 12:56:08.476507 synchwclock.sh[667]: rdate: adjust local clock by 26.519399 seconds Apr 26 12:56:08.491738 startadapter.sh[665]: Set NAT-address of 192.168.1.195 to 192.168.108.195. Apr 26 12:56:08.497808 startadapter.sh[665]: Set NAT-address of 192.168.1.196 to 192.168.108.196. Apr 26 12:56:08.504525 startadapter.sh[665]: Set NAT-address of 192.168.1.197 to 192.168.108.197. Apr 26 12:56:08.511683 startadapter.sh[665]: Set NAT-address of 192.168.1.198 to 192.168.108.198. Apr 26 12:56:08.517918 startadapter.sh[665]: Set NAT-address of 192.168.1.199 to 192.168.108.199. Apr 26 12:56:08.523939 startadapter.sh[665]: Set NAT-address of 192.168.1.200 to 192.168.108.200. Apr 26 12:56:08.529963 startadapter.sh[665]: Set NAT-address of 192.168.1.201 to 192.168.108.201. Apr 26 12:56:08.536475 startadapter.sh[665]: Set NAT-address of 192.168.1.202 to 192.168.108.202. Apr 26 12:56:08.542595 startadapter.sh[665]: Set NAT-address of 192.168.1.203 to 192.168.108.203. Apr 26 12:56:08.549108 startadapter.sh[665]: Set NAT-address of 192.168.1.204 to 192.168.108.204. Apr 26 12:56:08.560945 startadapter.sh[665]: Set NAT-address of 192.168.1.205 to 192.168.108.205. Apr 26 12:56:08.567236 startadapter.sh[665]: Set NAT-address of 192.168.1.206 to 192.168.108.206. Apr 26 12:56:08.737130 P@ck-Control[681]: [681 RdsMain]; [NOTICE] ++++++Initializing Rovema Data Server++++++ Apr 26 12:56:08.738209 P@ck-Control[681]: [681 RdsMain]; [DEBUG] parent exists already; PID of daemon is 773 Apr 26 12:56:08.738719 P@ck-Control[773]: [773 rds_daemonize]; [DEBUG] Daemon created Apr 26 12:56:08.739157 P@ck-Control[773]: [773 rds_daemonize]; [INFO] Deamon have found stacksize of 65536 Byte set it to 524288 Byte max 65536 KiB, Apr 26 12:56:08.739576 P@ck-Control[773]: [773 rds_daemonize]; [INFO] New stacksize of 524288 Byte should 524288 Byte max. 524288 KiB, Apr 26 12:56:08.740162 P@ck-Control[773]: [773 rds_daemonize]; [DEBUG] make FIFO /tmp/rdsCmd Apr 26 12:56:08.740477 P@ck-Control[773]: [773 rds_daemonize]; [DEBUG] stdin redirected to '/dev/null'; stdout & -err to '/var/log/rds.log' Apr 26 12:56:08.740705 P@ck-Control[773]: [773 rds_daemonize]; [DEBUG] stdin redirected to '/tmp/rdsCmd'; stdout & -err to '/var/log/rds.log' DONE! Apr 26 12:56:08.740929 P@ck-Control[773]: [773 init_rds]; [INFO] Working in directory '/opt/rovema/rds' Apr 26 12:56:08.741141 P@ck-Control[773]: [773 init_rds]; [DEBUG] Real directory is '/opt/rovema/rds'! Apr 26 12:56:08.741581 P@ck-Control[773]: [773 init_rds]; [INFO] Registering standard signal handler (all) Apr 26 12:56:08.741865 start-rds.sh[664]: 12:56:08.736 [681 RdsMain]; [NOTICE] heartbeat: 1 - exec: 0 - daemon: 1 -log-level: 7 - use-sram: 4 - signal handler: 0 - location: /opt/rovema/rds - rdf-file: TestHome.RDF Apr 26 12:56:08.742385 P@ck-Control[773]: [773 init_rds]; [DEBUG] Start hostBaseInit()!! Apr 26 12:56:08.743053 P@ck-Control[773]: [776 TicTask]; [DEBUG] fTicTask: Set CPU Affinity to 0x2; 0x0; Apr 26 12:56:08.743711 P@ck-Control[773]: [773 init_rds]; [DEBUG] hostBaseInit: at 26.04.2022 12:56:08.742 Apr 26 12:56:08.744128 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_RdsFile::setBackupDir() /opt/rovema/dailybackup/Tuesday/ time 1650960053 now 1650970568 Apr 26 12:56:08.744828 P@ck-Control[773]: [778 powerFailDetection]; [DEBUG] C_PowerFail::threadEntry 0xb21004c0!! Apr 26 12:56:08.746719 P@ck-Control[773]: Power: match_line( /sys/class/uio/uio0/name, uio_nvram) not found 2 Apr 26 12:56:08.747067 P@ck-Control[773]: Power: match_line( /sys/class/uio/uio0/name, simatic_ipc_nvram) not found 2 Apr 26 12:56:08.747351 P@ck-Control[773]: [778 powerFailDetection]; [INFO] no Janich & Klass - PC-S3 board_version 'RS-AA#012l' found Apr 26 12:56:08.749671 P@ck-Control[773]: [778 powerFailDetection]; [DEBUG] C_PowerFail::thread uio not found -1!! Apr 26 12:56:08.751238 P@ck-Control[773]: [776 TicTask]; [DEBUG] C_PowerFail() constructor ready 0xb21004c0! Apr 26 12:56:08.752163 P@ck-Control[773]: [773 init_rds]; [DEBUG] BackUpBasis = '/opt/rovema/dailybackup/Tuesday/04.' Apr 26 12:56:08.753840 P@ck-Control[773]: [773 init_rds]; [DEBUG] rename error.log. Apr 26 12:56:08.754086 P@ck-Control[773]: [773 init_rds]; [DEBUG] Rds - constructor! Apr 26 12:56:08.754951 P@ck-Control[773]: [782 tLgLst2File]; [DEBUG] RDS: start ll_task_writeInFile Apr 26 12:56:08.755195 P@ck-Control[773]: [783 rdsPollProc]; [DEBUG] C_RdsDataProcess::procPollTask(): cyclicCallSync = 0 Apr 26 12:56:08.755512 P@ck-Control[773]: [773 init_rds]; [DEBUG] Use SRAM 4! Apr 26 12:56:08.755736 P@ck-Control[773]: [773 init_rds]; [INFO] RDS: 1. NvRam found at /root/nvram0, size = 262144 Apr 26 12:56:08.756954 P@ck-Control[773]: [773 init_rds]; [INFO] C_RdsSram at 0xb1d4d008 old 0xb1e41008 initialized with init = '22.04.2022 11:25:12.598'/0x1805096F716; shutDown = '26.04.2022 12:51:24.135'/0x180657F5067 file 'TestHome.RDF' Apr 26 12:56:08.757341 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_RdsSram::C_RdsSram from 0xb1d4d008 to 0xb1d8d008 ############ Apr 26 12:56:08.757624 P@ck-Control[773]: [773 init_rds]; [NOTICE] Load RDF from file: 'TestHome.RDF'. Apr 26 12:56:08.766370 start-rds.sh[664]: 12:56:08.736 [681 RdsMain]; [NOTICE] heartbeat: 1 - exec: 0 - daemon: 1 -log-level: 7 - use-sram: 4 - signal handler: 0 - location: /opt/rovema/rds - rdf-file: TestHome.RDF Apr 26 12:56:08.766743 systemd[1]: Started Start the RDS, REUS and other deamons of P@ckControl. Apr 26 12:56:08.767067 systemd[1]: Starting Multi-User System. Apr 26 12:56:08.767396 systemd[1]: Reached target Multi-User System. Apr 26 12:56:08.771735 systemd[1]: Starting Start the Javis the Rovama HMI... Apr 26 12:56:08.774923 systemd[1]: Started Start the Javis the Rovama HMI. Apr 26 12:56:08.775376 systemd[1]: Starting Graphical Interface. Apr 26 12:56:08.775718 systemd[1]: Reached target Graphical Interface. Apr 26 12:56:08.776020 systemd[1]: Starting Starts the touchscreen recalibration if neccessary... Apr 26 12:56:08.777141 systemd[1]: Started Starts the touchscreen recalibration if neccessary. Apr 26 12:56:08.777533 systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 26 12:56:08.786270 systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 26 12:56:08.798802 systemd[1]: Startup finished in 2.020s (kernel) + 10.726s (userspace) = 12.747s. Apr 26 12:56:08.822102 systemd[1]: Stopping Start the Javis the Rovama HMI... Apr 26 12:56:08.825901 systemd[1]: Stopped Start the Javis the Rovama HMI. Apr 26 12:56:08.835738 recalibrate.sh[790]: Unable to connect to X server Apr 26 12:56:08.886499 systemd[1]: Starting Start the Javis the Rovama HMI... Apr 26 12:56:08.891061 systemd[1]: Started Start the Javis the Rovama HMI. Apr 26 12:56:08.907865 REUS[786]: [786 ReusMain]; [INFO] Trying to connect to server.. Apr 26 12:56:08.908368 start-rds.sh[664]: Start RDC REUS to 127.0.0.1 Apr 26 12:56:08.908877 REUS[786]: [813 tRdcBaREUS]; [NOTICE] Start RDC: REUS Apr 26 12:56:09.115472 start-rds.sh[664]: tcpdump: eth3: No such device exists Apr 26 12:56:09.115599 start-rds.sh[664]: (SIOCGIFHWADDR: No such device) Apr 26 12:56:09.156615 start-rds.sh[664]: tcpdump: eth3: No such device exists Apr 26 12:56:09.156814 start-rds.sh[664]: (SIOCGIFHWADDR: No such device) Apr 26 12:56:09.244130 P@ck-Control[773]: [778 powerFailDetection]; [WARNING] RdsSram C_RdsSram::copyToFileMap finished! Apr 26 12:56:09.258873 P@ck-Control[773]: [773 init_rds]; [INFO] NVRAM '/nvram0' is used, remanent data are unimpaired! Apr 26 12:56:09.358942 start-rds.sh[664]: /sys/kernel/debug/tracing/events/sched/filter Apr 26 12:56:09.359077 start-rds.sh[664]: /sys/kernel/debug/tracing/events/*/sched/filter Apr 26 12:56:09.588447 start-javis.sh[808]: Exception in thread "main" java.lang.InternalError: Can't connect to X11 window server using ':0' as the value of the DISPLAY variable. Apr 26 12:56:09.589227 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.initDisplay(Native Method) Apr 26 12:56:09.589736 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.access$200(X11GraphicsEnvironment.java:65) Apr 26 12:56:09.590394 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment$1.run(X11GraphicsEnvironment.java:110) Apr 26 12:56:09.590830 start-javis.sh[808]: at java.security.AccessController.doPrivileged(Native Method) Apr 26 12:56:09.591216 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.(X11GraphicsEnvironment.java:74) Apr 26 12:56:09.591777 start-javis.sh[808]: at java.lang.Class.forName0(Native Method) Apr 26 12:56:09.592158 start-javis.sh[808]: at java.lang.Class.forName(Class.java:195) Apr 26 12:56:09.592794 start-javis.sh[808]: at java.awt.GraphicsEnvironment.createGE(GraphicsEnvironment.java:102) Apr 26 12:56:09.593215 start-javis.sh[808]: at java.awt.GraphicsEnvironment.getLocalGraphicsEnvironment(GraphicsEnvironment.java:81) Apr 26 12:56:09.594412 start-javis.sh[808]: at java.awt.Window.initGC(Window.java:467) Apr 26 12:56:09.594879 start-javis.sh[808]: at java.awt.Window.init(Window.java:487) Apr 26 12:56:09.595256 start-javis.sh[808]: at java.awt.Window.(Window.java:536) Apr 26 12:56:09.595683 start-javis.sh[808]: at java.awt.Frame.(Frame.java:420) Apr 26 12:56:09.596054 start-javis.sh[808]: at javax.swing.JFrame.(JFrame.java:218) Apr 26 12:56:09.596438 start-javis.sh[808]: at javisWindows.JavisStartup.(JavisStartup.java:186) Apr 26 12:56:09.596828 start-javis.sh[808]: at Javis.main(Javis.java:45) Apr 26 12:56:09.601687 start-javis.sh[808]: javisReturn 1 Apr 26 12:56:09.603827 start-javis.sh[808]: End of script: start-Javis.sh Apr 26 12:56:09.703966 startadapter.sh[665]: > ro-adapter@2.3.4 start /opt/rovema/adapter Apr 26 12:56:09.704295 startadapter.sh[665]: > node ./app/adapter/standalone "--project=/opt/rovema/adapter/project.json" "--text-source=/opt/rovema/adapter/textsource.zip" "--bind=0.0.0.0" "--backend=ws://127.0.0.1:9002" "--aux-data" "./var/aux" Apr 26 12:56:09.912410 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_RdsDataStream::readStream: 18265 lines read! Apr 26 12:56:09.925079 P@ck-Control[773]: [773 init_rds]; [NOTICE] RDS: 0 ric-files found. Apr 26 12:56:09.926044 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS 0 ric's loaded; needed 1168 ms to load Datatree Apr 26 12:56:10.421262 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS writeStream '/opt/rovema/rds/TestHome.RDF.inwork' start Apr 26 12:56:10.779564 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS writeStream OK Apr 26 12:56:10.780151 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured Apr 26 12:56:10.780526 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured Apr 26 12:56:10.850821 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured Apr 26 12:56:10.932267 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured Apr 26 12:56:10.932687 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured Apr 26 12:56:10.962269 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured Apr 26 12:56:10.976374 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS lL_Startup begin Apr 26 12:56:10.978082 P@ck-Control[773]: [773 init_rds]; [DEBUG] ll_RestoreFromFile 'System.RDS.alarmList' start piDataFix 0x953e248 Apr 26 12:56:10.979862 P@ck-Control[773]: [773 init_rds]; [DEBUG] ll_RestoreFromFile 'System.RDS.alarmList' finish piDataFix 0x953e248 Apr 26 12:56:10.980434 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS link begin Apr 26 12:56:10.982739 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS processStartup begin Apr 26 12:56:10.983258 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS m_poOscilloscopeList begin Apr 26 12:56:10.984496 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS createConnections begin Apr 26 12:56:10.987181 P@ck-Control[773]: [773 init_rds]; [DEBUG] RDS -- start RMA-Server ## Apr 26 12:56:10.988969 P@ck-Control[773]: [773 init_rds]; [NOTICE] RMA-logging is now 'Off'! Apr 26 12:56:10.989963 P@ck-Control[773]: [773 init_rds]; [NOTICE] RDS [26.04.2022 12:56:10.989] loaded at g_poRDS = 0x952ba20 g_poRdcServer = 0x97f4358 OK! Apr 26 12:56:10.992990 P@ck-Control[773]: [773 init_rds]; [INFO] C_RdsExecutableList 4 start; 0 startUp ! Apr 26 12:56:10.994095 P@ck-Control[773]: [773 init_rds]; [NOTICE] Init MinMaxModules Apr 26 12:56:10.994933 P@ck-Control[773]: [773 init_rds]; [DEBUG] Finish RDC-Client!! : MinMaxModul Apr 26 12:56:11.015580 P@ck-Control[773]: [855 infoMinMaxModul]; [INFO] Finish Informtask of MinMaxModul -1- HostCPU(127.0.0.1): Apr 26 12:56:11.116229 P@ck-Control[773]: [773 init_rds]; [DEBUG] Delete C_RdsClientLocal of 'MinMaxModul' Apr 26 12:56:11.117149 P@ck-Control[773]: [773 init_rds]; [DEBUG] systemTicInit cycle: 1000000ns AxisTask: 10000000ns Apr 26 12:56:11.117878 P@ck-Control[773]: [773 init_rds]; [DEBUG] IPSettings Configuration Interface (Constructor) Apr 26 12:56:11.118405 P@ck-Control[773]: [773 init_rds]; [DEBUG] IP Configuration Interface (Constructor) Apr 26 12:56:11.119929 P@ck-Control[773]: [773 init_rds]; [DEBUG] Found from configuration of (eth0) IP: 192.168.19.108, Mask: 255.255.255.0, Gateway: 192.168.19.1! Apr 26 12:56:11.120253 P@ck-Control[773]: [773 init_rds]; [DEBUG] IP Configuration Interface (Constructor) Apr 26 12:56:11.120677 P@ck-Control[773]: [773 init_rds]; [DEBUG] Found from configuration of (eth1) IP: 192.168.1.102, Mask: 255.255.255.248, Gateway: 192.168.19.1! Apr 26 12:56:11.120929 P@ck-Control[773]: [773 init_rds]; [INFO] Initialize IP Configuration Module Apr 26 12:56:11.122878 P@ck-Control[773]: [773 init_rds]; [INFO] IP Settings interface initialized Apr 26 12:56:11.123179 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_RdsExecutable::execute : '/opt/rovema/system/timezone.sh System&' Apr 26 12:56:11.227281 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_RdsExecutable::execute : '/opt/rovema/system/getThinClientData.sh System.Version&' Apr 26 12:56:11.332477 P@ck-Control[773]: [773 init_rds]; [INFO] C_RdsExecutableList::start 4 started Apr 26 12:56:11.332937 P@ck-Control[773]: [773 init_rds]; [NOTICE] Start initSercos3Driver at 12:56:11.331 Apr 26 12:56:11.334598 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver::getRedundancy() is 0 (line 381) Apr 26 12:56:11.334869 P@ck-Control[773]: [773 init_rds]; [INFO] C_Sercos3Driver(): SoftMaster is activ!! Apr 26 12:56:11.335112 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver: load RT-Configuration from data-tree FOR 2 Slaves m_bAutoConfiguration = 0 Apr 26 12:56:11.335462 P@ck-Control[773]: [773 init_rds]; [INFO] Init Sercos-SoftMaster!! Apr 26 12:56:11.335809 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 0 to 'eno2'. Apr 26 12:56:11.336044 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (788):RTLX_SetInterfacName(): New interface name of instance 0 is 'eno2'. Apr 26 12:56:11.336265 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 0 to 'eth2'. Apr 26 12:56:11.336599 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (788):RTLX_SetInterfacName(): New interface name of instance 0 is 'eth2'. Apr 26 12:56:11.337485 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 1 to 'eth3'. Apr 26 12:56:11.337802 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (788):RTLX_SetInterfacName(): New interface name of instance 1 is 'eth3'. Apr 26 12:56:11.338084 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (839):RTLX_GetNicName of instance 0 'eth2'. Apr 26 12:56:11.338371 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1456):RTLX_InitNicTimedTransmission() i 0, 'eth2' Apr 26 12:56:11.338929 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (839):RTLX_GetNicName of instance 0 'eth2'. Apr 26 12:56:11.339211 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1154):RTLX_ReadNicDataFromFS: Physical path to device is '../../devices/pci0000:00/0000:00:1c.6/0000:03:00.0/net/eth2¶ÿÿÿÿÿÿÿÿ'. Apr 26 12:56:11.339546 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1173):RTLX_ReadNicDataFromFS: devPath is '../../devices/pci0000:00/0000:00:1c.6/0000:03:00.0' Apr 26 12:56:11.339817 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1178):RTLX_ReadNicDataFromFS: devPath is '../../devices/pci0000:00/0000:00:1c.6/0000:03:00.0' Apr 26 12:56:11.340481 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1478):Probing device 0000:03:00.0 for port 0 Apr 26 12:56:11.340752 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1501):Attaching to 0000:03:00.0 for port 0 Apr 26 12:56:11.341011 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1532):Attach to NIC device 0000:03:00.0 for port 0 successful. Apr 26 12:56:11.341282 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1583):MAC address of NIC 6 device: d4:f5:27:e5:a8:90 Apr 26 12:56:11.341567 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1593):igb_attach_tx 0. return = 0/home/vmuser/working_copy/Communication/Sercos3Base/src/RTLX/RTLX_S3SM_NIC_TIMED.c 1593 Apr 26 12:56:11.342653 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1610):igb_init port 0. count 1/home/vmuser/working_copy/Communication/Sercos3Base/src/RTLX/RTLX_S3SM_NIC_TIMED.c 1610 Apr 26 12:56:11.342939 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1760):Successfully allocated 8 DMA pages with 4096 bytes each for port 0 Apr 26 12:56:11.343647 P@ck-Control[773]: RTLX_CheckConnected 0 dev 19 get linkSpeed 100 is up 1 duplex 2; /home/vmuser/working_copy/Communication/Sercos3Base/src/RTLX/RTLX_S3SM_NIC_TIMED.c 2132; Apr 26 12:56:11.344294 P@ck-Control[773]: [773 init_rds]; [NOTICE] RTLX (1795):RTLX_InitNicTimedTransmission sleept until 5 to wait for connections port0 = 0; port1 = -1; Apr 26 12:56:11.345299 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosDriverBase - SICE_Init is called with m_poSoftMasterInstance = 0x985a178, 0 - ret is 0 ### CP -1 Apr 26 12:56:11.345630 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosDriverBase::initSercosSoftMaster finished CP -1 . Apr 26 12:56:11.346658 P@ck-Control[773]: INFO: sercos initialize ucc of 0! Apr 26 12:56:11.346946 P@ck-Control[773]: INFO: sercos ucc MAC of 'serc0' to 06:f5:27:e5:a8:90! Apr 26 12:56:11.352358 P@ck-Control[773]: INFO: sercos ucc of 'serc0' is initialized successfully fd: 21! Apr 26 12:56:11.352730 P@ck-Control[773]: sercos ucc of 'serc0' initialized successfully! Apr 26 12:56:11.354147 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosDriverBase::SercosSetupUCC done m_UccFd 21 . Apr 26 12:56:11.354523 P@ck-Control[773]: setUCCAddr HW-ADDR: 06-f5-27-e5-a8-90; Apr 26 12:56:11.354800 P@ck-Control[773]: setUCCAddr setup ifr done Apr 26 12:56:11.355057 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver: Init Sercos3Slaves Apr 26 12:56:11.359829 P@ck-Control[773]: [773 init_rds]; [DEBUG] Found SercosSubBusTunnel at Boards.vxhost.Hardware.Sercos3Interface.Slaves.IO_Modul_102.IOLink.Module_1 Apr 26 12:56:11.360231 P@ck-Control[773]: [773 init_rds]; [DEBUG] Found SercosIoLinkModule 1 with 8 ports at Boards.vxhost.Hardware.Sercos3Interface.Slaves.IO_Modul_102.IOLink.Module_1 Apr 26 12:56:11.360583 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosSubBusTunnel::allocateSubBusTunnelPDUs size 1926, m_poRequestSubBusPDU 0x9945ce4, m_poConfirmSubBusPDU 0x994651c, m_poSubBusTunnelConfirmIDN 0x9946480 Apr 26 12:56:11.379550 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosSubBusTunnel::writeSubBusSlaveConfiguration 8 [4 4 0 0 0 0 0 0] Apr 26 12:56:11.379959 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosSubBusTunnel::writeInPutDataLength 8 [16 16 0 0 0 0 0 0] Apr 26 12:56:11.380279 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_SercosSubBusTunnel::writeOutPutDataLength 8 [0 0 0 0 0 0 0 0] Apr 26 12:56:11.380622 P@ck-Control[773]: [773 init_rds]; [INFO] C_Sercos3Driver 2 Slaves configured! Apr 26 12:56:11.380894 P@ck-Control[773]: [948 rds_plc]; [INFO] plcInit -> BootfileDirectory = '/opt/rovema/plc'; sFileDirectory = '/opt/rovema/plc' Apr 26 12:56:11.381166 P@ck-Control[773]: [773 init_rds]; [INFO] WaitForStartup done Apr 26 12:56:11.381457 P@ck-Control[773]: [953 SercosCyclicBus]; [DEBUG] C_Sercos3Driver::cycleThread: Set CPU Affinity to 0x2; 0x0; Apr 26 12:56:11.381726 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (283):Successfully started NIC-timed alarm thread Apr 26 12:56:11.382678 P@ck-Control[773]: [954 SercosService]; [INFO] C_Sercos3Driver::_sercosServiceThread() started Apr 26 12:56:11.383001 P@ck-Control[773]: [952 SercosInit]; [DEBUG] C_Sercos3Driver::initThread(): set CP 2! (m_bAutoConfiguration = 0) Apr 26 12:56:11.383290 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseNotInit... Apr 26 12:56:11.384674 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:11.385030 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseHardwareInit... Apr 26 12:56:11.385306 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDeb start! Apr 26 12:56:11.385625 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp();wait for m_ReadyForStartup ! Ok 1 Apr 26 12:56:11.390562 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcMem start! Apr 26 12:56:11.400771 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcComm start! Apr 26 12:56:11.410979 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcRoot start! Apr 26 12:56:11.421184 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcSvis start! Apr 26 12:56:11.431373 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run CritAnal start! Apr 26 12:56:11.442098 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run DChanges start! Apr 26 12:56:11.451726 P@ck-Control[773]: [948 rds_plc]; [DEBUG] ++ firmwareSystickInstall called from PLC t2sec 1000 Apr 26 12:56:11.452253 P@ck-Control[773]: [948 rds_plc]; [INFO] Start initModBusDriver at 12:56:11.410 Apr 26 12:56:11.584990 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCommunicationParameter... Apr 26 12:56:11.586231 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetNrtMode... Apr 26 12:56:11.587127 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseInitConfigStruct...,00 Apr 26 12:56:11.589057 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase0...; cycle 110 Apr 26 12:56:11.890951 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:11.909371 P@ck-Control[773]: [852 tRmaServer]; [DEBUG] C_SocketDataChannel::AcceptConnectionRequest - Set Extended data-length in server! Apr 26 12:56:11.983268 P@ck-Control[773]: [984 infoModBusDriver]; [INFO] Finish Informtask of ModBusDriver -1- HostCPU(127.0.0.1): Apr 26 12:56:11.999006 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:12.010349 P@ck-Control[773]: [1021 rmaSrSes01]; [DEBUG] C_SocketDataChannel::AcceptConnectionRequest - Set Extended data-length in server! Apr 26 12:56:12.083291 P@ck-Control[773]: [948 rds_plc]; [DEBUG] Delete C_RdsClientLocal of 'ModBusDriver' Apr 26 12:56:12.083780 P@ck-Control[773]: [948 rds_plc]; [INFO] initModBusDriver no ModBus found Apr 26 12:56:12.084039 P@ck-Control[773]: [948 rds_plc]; [INFO] Start initNetXDriver at 12:56:11.726 Apr 26 12:56:12.114544 P@ck-Control[773]: [1024 infoNetXDriver]; [INFO] Finish Informtask of NetXDriver -1- HostCPU(127.0.0.1): Apr 26 12:56:12.214553 P@ck-Control[773]: [948 rds_plc]; [DEBUG] Delete C_RdsClientLocal of 'NetXDriver' Apr 26 12:56:12.215000 P@ck-Control[773]: [948 rds_plc]; [INFO] initNetXDriver no NetX found Apr 26 12:56:12.216908 P@ck-Control[773]: [1029 plcEnableWD]; [INFO] plcWatchdogEnableTask started Apr 26 12:56:12.217289 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv0 start! Apr 26 12:56:12.227305 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv1 start! Apr 26 12:56:12.230373 REUS[786]: [786 ReusMain]; [NOTICE] REUS connected Apr 26 12:56:12.231355 REUS[786]: [786 ReusMain]; [INFO] Initializing tracer, set trace buffer to 20000, results will be stored here: /var/log/trace.dat Apr 26 12:56:12.237566 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv2 start! Apr 26 12:56:12.247745 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv3 start! Apr 26 12:56:12.257977 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv4 start! Apr 26 12:56:12.268219 P@ck-Control[773]: [948 rds_plc]; [DEBUG] sys_task_run tpcDrv5 start! Apr 26 12:56:12.278452 P@ck-Control[773]: [948 rds_plc]; [INFO] ProConOS Linux V4.2.0245 Apr 26 12:56:12.278861 P@ck-Control[773]: [948 rds_plc]; [INFO] -------- (c) 2013 KW-Software GmbH D-32657 Lemgo Apr 26 12:56:12.279202 P@ck-Control[773]: [948 rds_plc]; [INFO] PLC-Thread with id 948 runs on cpu 0 only! Apr 26 12:56:12.281299 P@ck-Control[773]: [852 tRmaServer]; [NOTICE] C_SocketDataChannel::AcceptConnectionRequest - the RMA_Version of per is older so it could happen that data are destroyed! Apr 26 12:56:12.386371 P@ck-Control[773]: [1038 rmaSrSes02]; [NOTICE] C_SocketDataChannel::AcceptConnectionRequest - the RMA_Version of per is older so it could happen that data are destroyed! Apr 26 12:56:12.492269 start-rds.sh[664]: === /opt/rovema/system/timezone.sh -- start writing /var/log/timezonelist.txt === Apr 26 12:56:12.525151 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase0 finished!! Apr 26 12:56:12.525621 P@ck-Control[773]: [954 SercosService]; [INFO] SD3_CreateAddressTable for 2 slaves Apr 26 12:56:12.525894 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3Driver::setUpSlaveAddresses finished at 1.try to CreateAddressTable, found 2 of 2 slaves! m_bAppointSercosAddressesNessecarry = 0! Apr 26 12:56:12.526166 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '0' with _switchDown = 0; m_bAutoConfiguration = 0! Apr 26 12:56:12.526568 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '0' (1) with bReady = 1! Apr 26 12:56:12.526820 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '0' with bReady = 1! Apr 26 12:56:12.527280 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase1... Apr 26 12:56:12.540966 P@ck-Control[773]: [954 SercosService]; [INFO] m_CSMD_Instance.rPriv.pusTxRam is now :0x985c17c! Apr 26 12:56:12.558998 P@ck-Control[773]: [1038 rmaSrSes02]; [ERROR] RDS_ERROR:getNodeToDisplayIt: NodeID of Basis: 0000 = ' >>Invalide NodeID<< 0000' Name '.Boards.vxhost.Hardware.Sercos3Interface.RestartAutoConfig.ExternalUtility.Start' not found! Apr 26 12:56:12.693073 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase1 finished!! Apr 26 12:56:12.693499 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '1' with _switchDown = 0; m_bAutoConfiguration = 0! Apr 26 12:56:12.693789 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '1' (1) with bReady = 1! Apr 26 12:56:12.694073 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '1' with bReady = 1! Apr 26 12:56:12.694970 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase2... Apr 26 12:56:12.834985 P@ck-Control[773]: [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase2 finished!! Apr 26 12:56:12.837015 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCheckVersion... Apr 26 12:56:12.861291 REUS[786]: [786 ReusMain]; [INFO] Initializing updater module... Apr 26 12:56:12.865836 REUS[786]: [786 ReusMain]; [ERROR] No device available or device has been unplugged. Apr 26 12:56:12.866297 REUS[786]: [786 ReusMain]; [ERROR] Update failed: No device available or device has been unplugged. Apr 26 12:56:12.866746 REUS[786]: [786 ReusMain]; [ERROR] No device available or device has been unplugged. Apr 26 12:56:12.867624 REUS[786]: [786 ReusMain]; [INFO] Updater initialization done Apr 26 12:56:12.876719 REUS[786]: [786 ReusMain]; [INFO] SnapShot initialized... Apr 26 12:56:12.877053 REUS[786]: [786 ReusMain]; [INFO] Watchdog initialized Apr 26 12:56:12.877301 REUS[786]: [1175 WatchDog]; [NOTICE] ./rds --log-level=7(/proc/773) is running and will be monitored by watchdog. Apr 26 12:56:12.877553 REUS[786]: [786 ReusMain]; [NOTICE] CopyToUSB initialized. Apr 26 12:56:12.877791 REUS[786]: [1175 WatchDog]; [ERROR] /bin/bash /opt/rovema/javis/startJavis.sh() is not running and will NOT be monitored by watchdog. Apr 26 12:56:12.878022 REUS[786]: [786 ReusMain]; [NOTICE] REUS initialized successfully Apr 26 12:56:12.878240 REUS[786]: [1175 WatchDog]; [ERROR] /bin/bash /usr/bin/start-javis.sh() is not running and will NOT be monitored by watchdog. Apr 26 12:56:12.878494 REUS[786]: [1175 WatchDog]; [NOTICE] /bin/bash /usr/bin/startadapter.sh(/proc/665) is running and will be monitored by watchdog. Apr 26 12:56:12.893003 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:12.894973 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:12.943019 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:56:13.005009 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCheckVersion finished! Apr 26 12:56:13.005457 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '2' with _switchDown = 0; m_bAutoConfiguration = 0! Apr 26 12:56:13.005756 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase startUp! Apr 26 12:56:13.006464 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '2' (0) with bReady = 1! Apr 26 12:56:13.006756 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '2' (1) with bReady = 1! Apr 26 12:56:13.007082 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!! Apr 26 12:56:13.007352 P@ck-Control[773]: [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1. Apr 26 12:56:13.007600 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '2' with bReady = 1! Apr 26 12:56:13.007855 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp();wait for m_ReadyForStartup= 1 ! Ok 1 Apr 26 12:56:13.026624 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp(); setUpNRTchannel from startUp! Apr 26 12:56:13.027071 P@ck-Control[773]: setUCCAddr HW-ADDR: 06-f5-27-e5-a8-90; Apr 26 12:56:13.027376 P@ck-Control[773]: setUCCAddr setup ifr done Apr 26 12:56:13.071459 P@ck-Control[773]: [952 SercosInit]; [DEBUG] C_Sercos3Driver::_initThread set m_setCommunicationPhase to 4 again! Apr 26 12:56:13.883133 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkPort::setConnectedIOLinkSlaveID of Port 1, FunctionID 0, DeviceID 8388772, dVendorID 26; Apr 26 12:56:13.883663 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkPort::setConnectedIOLinkSlaveID of Port 2, FunctionID 0, DeviceID 123, dVendorID 333; Apr 26 12:56:14.310000 ntpd[592]: Listen normally on 10 serc0 192.168.108.254 UDP 123 Apr 26 12:56:14.310404 ntpd[592]: Listen normally on 11 eth2 fe80::d6f5:27ff:fee5:a890 UDP 123 Apr 26 12:56:14.310674 ntpd[592]: Listen normally on 12 serc0 fe80::4f5:27ff:fee5:a890 UDP 123 Apr 26 12:56:14.311076 ntpd[592]: peers refreshed Apr 26 12:56:14.784997 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3Driver::finishTransferOfPhase(); setUpNRTchannel from finishTransferOfPhase! Apr 26 12:56:14.785414 P@ck-Control[773]: setUCCAddr HW-ADDR: 06-f5-27-e5-a8-90; Apr 26 12:56:14.785667 P@ck-Control[773]: setUCCAddr setup ifr done Apr 26 12:56:14.787362 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetGetTimingData ... Apr 26 12:56:14.877837 startadapter.sh[665]: WebSocketConnection::send() called without connection Apr 26 12:56:14.878739 startadapter.sh[665]: WebSocketConnection::send() called without connection Apr 26 12:56:14.879207 startadapter.sh[665]: WebSocketConnection::send() called without connection Apr 26 12:56:14.879588 startadapter.sh[665]: WebSocketConnection::send() called without connection Apr 26 12:56:15.151052 P@ck-Control[773]: [954 SercosService]; [DEBUG] Check Realtime Connection of 03-A101 MDT 24 Bytes, AT 40 Bytes Apr 26 12:56:15.151444 P@ck-Control[773]: [954 SercosService]; [DEBUG] Check Realtime Connection of 03-A163 MDT 8 Bytes, AT 8 Bytes Apr 26 12:56:15.151699 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetGetTimingData finished! Apr 26 12:56:15.154383 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCalculateTiming finished! Apr 26 12:56:15.154935 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetTransmitTiming ... Apr 26 12:55:48.322690 systemd[1]: Time has been changed Apr 26 12:55:48.339239 P@ck-Control[773]: [850 RecvWebSocket]; [DEBUG] C_RdsWebsocketInterface::on_open - poClient 'WS_Client -1- WebSocket(127.0.0.1): ' 1 / Apr 26 12:55:48.350275 startadapter.sh[665]: WebSocket connection ws://127.0.0.1:9002 established Apr 26 12:55:48.366518 P@ck-Control[773]: [850 RecvWebSocket]; [INFO] C_RdsWsHmiConnection::newLogInDetected - called by Adapter -1- WebSocket(127.0.0.1): for user: Adapter Apr 26 12:55:48.431467 startadapter.sh[665]: #033[31merror#033[39m: Failed to fetch RDF from backend: Error: Unable to read /var/log/: Error: EISDIR: illegal operation on a directory, read Apr 26 12:55:48.836819 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetTransmitTiming finished! Apr 26 12:55:48.838759 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase3 ... Apr 26 12:55:49.578853 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase3 finished Set 4, SwitchPhase: 1! Apr 26 12:55:49.579337 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '3' with _switchDown = 0; m_bAutoConfiguration = 0! Apr 26 12:55:49.579704 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '3' (1) with bReady = 1! Apr 26 12:55:49.579998 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosIoLinkModule '03-A101:1': Type 0x10201; State: 10 Apr 26 12:55:49.580315 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!! Apr 26 12:55:49.580598 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosIoLinkModule '03-A101:1': Type 0x10201; State: 10 Apr 26 12:55:49.580926 P@ck-Control[773]: [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1. Apr 26 12:55:49.581213 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '3' with bReady = 1! Apr 26 12:55:49.581498 P@ck-Control[773]: [954 SercosService]; [DEBUG] NIC-timed transmission times: MDT: 100ns; AT: 41060ns; UCC: 399530ns Apr 26 12:55:49.682820 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase4 ... Apr 26 12:55:49.747806 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase4 finished Set 4, SwitchPhase: 0! Apr 26 12:55:49.748153 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '4' with _switchDown = 0; m_bAutoConfiguration = 0! Apr 26 12:55:49.748437 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '4' (1) with bReady = 1! Apr 26 12:55:49.748726 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!! Apr 26 12:55:49.748987 P@ck-Control[773]: [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1. Apr 26 12:55:49.749242 P@ck-Control[773]: [954 SercosService]; [DEBUG] SercosDriver: TranferData of CP4 - Sync_currentTime at 26.04.2022 12:55:49.747 Apr 26 12:55:49.749486 P@ck-Control[773]: [954 SercosService]; [DEBUG] Sercos3IOTest::startup OK 1 '0x99' = IO_Modul_102 Apr 26 12:55:49.749783 P@ck-Control[773]: [954 SercosService]; [DEBUG] Sercos3IOTest::startup OK 1 '0x41fe' = IO_Modul_163 Apr 26 12:55:49.750029 P@ck-Control[773]: [954 SercosService]; [DEBUG] transferDataOfPhase '4' with bReady = 1! Apr 26 12:55:49.750292 P@ck-Control[773]: [954 SercosService]; [DEBUG] NIC-timed transmission times: MDT: 100ns; AT: 41060ns; UCC: 399530ns Apr 26 12:55:49.771927 P@ck-Control[773]: [952 SercosInit]; [DEBUG] C_Sercos3Driver::_initThread finished; m_ReadyForStartup=1; bOk = 1, CSMD_FUNC_RET_END_OF_LIST = 0x41005! Apr 26 12:55:49.772352 P@ck-Control[773]: [952 SercosInit]; [INFO] SD3_CreateAddressTable for 2 slaves Apr 26 12:55:49.772702 P@ck-Control[773]: [952 SercosInit]; [DEBUG] C_Sercos3Driver::setUpSlaveAddresses finished at 2.try to CreateAddressTable, found 2 of 2 slaves! m_bAppointSercosAddressesNessecarry = 0! Apr 26 12:55:49.783442 P@ck-Control[773]: [773 init_rds]; [INFO] finish C_Sercos3Driver::startUp(); bOk = 1; timeOut = 4312; CP = 4 at 26.04.2022 12:55:49.781 ! (state 23 = 'eSercosPhaseHotPlug') Apr 26 12:55:49.791639 P@ck-Control[773]: [773 init_rds]; [INFO] Sercos Startup done Apr 26 12:55:49.792075 P@ck-Control[773]: [773 init_rds]; [INFO] C_RdsExecutableList::startUp 0 started Apr 26 12:55:49.892757 P@ck-Control[773]: [948 rds_plc]; [NOTICE] C_Dio_PowerFail: no node 'Boards.vxhost.Hardware.PowerDetection' found! Apr 26 12:55:49.893213 P@ck-Control[773]: [773 init_rds]; [DEBUG] Finish RDC-Client!! : RdsExecutable Apr 26 12:55:49.893511 P@ck-Control[773]: [948 rds_plc]; [NOTICE] C_Dio_PowerFail: will be deleted! Apr 26 12:55:49.900787 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:55:49.912886 P@ck-Control[773]: [853 infoRdsExecutable]; [INFO] Finish Informtask of RdsExecutable -1- HostCPU(127.0.0.1): Apr 26 12:55:49.992836 P@ck-Control[773]: [948 rds_plc]; [NOTICE] Plc startup : Apr 26 12:55:49.993270 P@ck-Control[773]: [948 rds_plc]; [NOTICE] Plc startup executed Apr 26 12:55:50.012948 P@ck-Control[773]: [773 init_rds]; [DEBUG] Delete C_RdsClientLocal of 'RdsExecutable' Apr 26 12:55:50.209950 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !! Apr 26 12:55:50.355779 P@ck-Control[773]: [781 rdsRmaServ]; [DEBUG] _RdsDataStream::_saveFullNodeStructure of 'System.RDS.LatestDataTree' ready Apr 26 12:55:51.625825 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !! Apr 26 12:55:51.771964 P@ck-Control[773]: [952 SercosInit]; [DEBUG] C_Sercos3Driver::initThread(): finished normal! Apr 26 12:55:53.041821 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !! Apr 26 12:55:53.909802 P@ck-Control[773]: [953 SercosCyclicBus]; [NOTICE] RTLX (1035):Successfully transmitted UCC #0 packet via NIC on port 0 Apr 26 12:55:54.020713 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured Apr 26 12:55:54.021173 P@ck-Control[773]: [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured Apr 26 12:55:54.181274 P@ck-Control[773]: [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured Apr 26 12:55:54.200150 P@ck-Control[773]: [773 init_rds]; [DEBUG] StartMainLoop Apr 26 12:55:54.460826 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !! Apr 26 12:55:55.651966 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'DeviceAccessLocks' success 1!! Apr 26 12:56:02.280985 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Hysteresis' success 1 value 82!! Apr 26 12:56:02.281393 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointMode' success 1 value 1!! Apr 26 12:56:02.281715 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointLogic' success 1 value 0!! Apr 26 12:56:02.281996 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutA_SwitchParameters' success 1!! Apr 26 12:56:02.823936 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SetPointHigh' success 1 value 11468!! Apr 26 12:56:02.824325 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SetPointLow' success 1 value 9830!! Apr 26 12:56:02.824579 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutB_SetPoints' success 1!! Apr 26 12:56:03.445879 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Hysteresis' success 1 value 82!! Apr 26 12:56:03.446284 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointMode' success 1 value 1!! Apr 26 12:56:03.446543 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointLogic' success 1 value 0!! Apr 26 12:56:03.446848 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutB_SwitchParameters' success 1!! Apr 26 12:56:08.368881 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Setpoint_SP2' success 1 value 0!! Apr 26 12:56:08.369279 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkSignedIntValue::writeToIoLink 'Setpoint_SP1_in___between_mark_and_background' Apr 26 12:56:08.369539 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkSignedIntValue::writeToIoLink() of Real 'Setpoint_SP1_in___between_mark_and_background' value -3 -> -3 offset 0 gradient 1 !! Apr 26 12:56:08.369862 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkSignedIntValue::writeToIoLink() of 'Setpoint_SP1_in___between_mark_and_background' success 1 value -3!! Apr 26 12:56:08.370103 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'Qint_1_SP1_SP2' success 1!! Apr 26 12:56:08.850883 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Switchpoint_hysteresis' success 1 value 0!! Apr 26 12:56:08.851277 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Switchpoint_mode___Operating_mode' success 1 value 128!! Apr 26 12:56:08.851537 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Switchpoint_logic___Inversion' success 1 value 128!! Apr 26 12:56:08.851847 P@ck-Control[773]: [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'Qint_1_configuration' success 1!! Apr 26 12:59:41.478748 synchwclock.sh[667]: unsynchronised Apr 26 12:59:41.479222 synchwclock.sh[667]: polling server every 64 s Apr 26 13:03:41.481645 synchwclock.sh[667]: unsynchronised Apr 26 13:03:41.482239 synchwclock.sh[667]: polling server every 64 s Apr 26 13:05:01.214989 CRON[1218]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:05:01.215818 CRON[1219]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:05:01.218610 CRON[1218]: pam_unix(cron:session): session closed for user root Apr 26 13:07:41.484445 synchwclock.sh[667]: unsynchronised Apr 26 13:07:41.484775 synchwclock.sh[667]: polling server every 64 s Apr 26 13:10:34.678487 systemd[1]: Starting Cleanup of Temporary Directories... Apr 26 13:10:34.687069 systemd[1]: Started Cleanup of Temporary Directories. Apr 26 13:11:41.487648 synchwclock.sh[667]: synchronised to NTP server (124.216.164.14) at stratum 2 Apr 26 13:11:41.487970 synchwclock.sh[667]: time correct to within 18 ms Apr 26 13:11:41.488494 synchwclock.sh[667]: polling server every 64 s Apr 26 13:11:42.500434 synchwclock.sh[667]: hwclock: Could not open file with the clock adjustment parameters in it (/etc/adjtime) for writing: Read-only file system Apr 26 13:11:42.501394 synchwclock.sh[667]: hwclock: Drift adjustment parameters not updated. Apr 26 13:15:01.222191 CRON[1241]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:15:01.222702 CRON[1242]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:15:01.225064 CRON[1241]: pam_unix(cron:session): session closed for user root Apr 26 13:17:01.227671 CRON[1244]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:17:01.228445 CRON[1245]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Apr 26 13:17:01.231075 CRON[1244]: pam_unix(cron:session): session closed for user root Apr 26 13:25:01.234174 CRON[1255]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:25:01.235090 CRON[1256]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:25:01.237328 CRON[1255]: pam_unix(cron:session): session closed for user root Apr 26 13:35:01.240779 CRON[1268]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:35:01.243430 CRON[1269]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:35:01.243534 CRON[1268]: pam_unix(cron:session): session closed for user root Apr 26 13:45:01.247099 CRON[1281]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:45:01.248151 CRON[1282]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:45:01.249860 CRON[1281]: pam_unix(cron:session): session closed for user root Apr 26 13:48:33.662657 sshd[1286]: Accepted password for root from 192.168.19.107 port 51244 ssh2 Apr 26 13:48:33.665653 sshd[1286]: pam_unix(sshd:session): session opened for user root by (uid=0) Apr 26 13:49:30.126046 sshd[1294]: Accepted password for root from 192.168.19.107 port 51311 ssh2 Apr 26 13:49:30.128760 sshd[1294]: pam_unix(sshd:session): session opened for user root by (uid=0) Apr 26 13:55:01.253407 CRON[1308]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 13:55:01.256065 CRON[1309]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 13:55:01.256255 CRON[1308]: pam_unix(cron:session): session closed for user root Apr 26 14:05:01.259718 CRON[1321]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 14:05:01.260265 CRON[1322]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 14:05:01.262370 CRON[1321]: pam_unix(cron:session): session closed for user root Apr 26 14:15:01.266009 CRON[1336]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 14:15:01.268922 CRON[1337]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 14:15:01.269037 CRON[1336]: pam_unix(cron:session): session closed for user root Apr 26 14:17:01.271454 CRON[1341]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 14:17:01.271998 CRON[1342]: (root) CMD ( cd / && run-parts --report /etc/cron.hourly) Apr 26 14:17:01.274116 CRON[1341]: pam_unix(cron:session): session closed for user root Apr 26 14:25:01.277282 CRON[1350]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 14:25:01.278235 CRON[1351]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 14:25:01.280112 CRON[1350]: pam_unix(cron:session): session closed for user root Apr 26 14:35:01.283641 CRON[1363]: pam_unix(cron:session): session opened for user root by (uid=0) Apr 26 14:35:01.284218 CRON[1364]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1) Apr 26 14:35:01.286422 CRON[1363]: pam_unix(cron:session): session closed for user root