pastebin - collaborative debugging tool
rovema.kpaste.net RSS


syslog jessie/wulsch bad-bios
Posted by Anonymous on Tue 26th Apr 2022 13:48
raw | new post

  1. Apr 26 12:55:40.739047 rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="557" x-info="http://www.rsyslog.com"] start
  2. Apr 26 12:55:40.743273 systemd[1]: Started Apply Kernel Variables.
  3. Apr 26 12:55:40.743290 systemd[1]: Started Create Static Device Nodes in /dev.
  4. Apr 26 12:55:40.743296 systemd[1]: Starting udev Kernel Device Manager...
  5. Apr 26 12:55:40.743300 systemd[1]: Started udev Kernel Device Manager.
  6. Apr 26 12:55:40.743304 systemd[1]: Starting Copy rules generated while the root was ro...
  7. Apr 26 12:55:40.743309 systemd[1]: Starting LSB: Set preliminary keymap...
  8. Apr 26 12:55:40.743314 systemd[1]: Started Copy rules generated while the root was ro.
  9. Apr 26 12:55:40.743320 systemd[1]: Started LSB: Set preliminary keymap.
  10. Apr 26 12:55:40.743324 systemd[1]: Starting Show Plymouth Boot Screen...
  11. Apr 26 12:55:40.743329 systemd[1]: Starting Remount Root and Kernel File Systems...
  12. Apr 26 12:55:40.743333 systemd[1]: Started Remount Root and Kernel File Systems.
  13. Apr 26 12:55:40.743338 systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
  14. Apr 26 12:55:40.743343 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
  15. Apr 26 12:55:40.743347 systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
  16. Apr 26 12:55:40.743352 systemd[1]: Starting Paths.
  17. Apr 26 12:55:40.743358 systemd[1]: Reached target Paths.
  18. Apr 26 12:55:40.743363 systemd[1]: Starting system-systemd\x2dbacklight.slice.
  19. Apr 26 12:55:40.743367 systemd[1]: Created slice system-systemd\x2dbacklight.slice.
  20. Apr 26 12:55:40.743372 systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:acpi_video0...
  21. Apr 26 12:55:40.743377 systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:acpi_video0.
  22. Apr 26 12:55:40.743382 systemd[1]: Found device CFAST-8G0-XIE82 pack-control.
  23. Apr 26 12:55:40.743387 systemd[1]: Mounting /opt...
  24. Apr 26 12:55:40.743391 systemd[1]: Found device CFAST-8G0-XIE82 roothome.
  25. Apr 26 12:55:40.743400 systemd[1]: Found device CFAST-8G0-XIE82 backup.
  26. Apr 26 12:55:40.743404 systemd[1]: Mounting /root...
  27. Apr 26 12:55:40.743409 systemd[1]: Mounted /opt.
  28. Apr 26 12:55:40.743413 systemd[1]: Mounting /opt/backup...
  29. Apr 26 12:55:40.743418 systemd[1]: Mounted /root.
  30. Apr 26 12:55:40.743423 systemd[1]: Mounted /opt/backup.
  31. Apr 26 12:55:40.743427 systemd[1]: Starting Local File Systems.
  32. Apr 26 12:55:40.743432 systemd[1]: Reached target Local File Systems.
  33. Apr 26 12:55:40.743436 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
  34. Apr 26 12:55:40.743443 systemd[1]: Starting Create Volatile Files and Directories...
  35. Apr 26 12:55:40.743447 systemd[1]: Starting Remote File Systems.
  36. Apr 26 12:55:40.743452 systemd[1]: Reached target Remote File Systems.
  37. Apr 26 12:55:40.743456 systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
  38. Apr 26 12:55:40.743461 systemd[1]: Starting LSB: Set console font and keymap...
  39. Apr 26 12:55:40.743465 systemd[1]: Starting LSB: Raise network interfaces....
  40. Apr 26 12:55:40.743470 systemd[1]: Started LSB: Set console font and keymap.
  41. Apr 26 12:55:40.743475 systemd[1]: Started Create Volatile Files and Directories.
  42. Apr 26 12:55:40.743482 systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
  43. Apr 26 12:55:40.743493 systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
  44. Apr 26 12:55:40.743501 systemd[1]: Starting Update UTMP about System Boot/Shutdown...
  45. Apr 26 12:55:40.743506 systemd[1]: Started Update UTMP about System Boot/Shutdown.
  46. Apr 26 12:55:40.743510 systemd[1]: Starting Sound Card.
  47. Apr 26 12:55:40.743515 systemd[1]: Reached target Sound Card.
  48. Apr 26 12:55:40.743520 dhclient: Internet Systems Consortium DHCP Client 4.3.1
  49. Apr 26 12:55:40.743524 dhclient: Copyright 2004-2014 Internet Systems Consortium.
  50. Apr 26 12:55:40.743530 dhclient: All rights reserved.
  51. Apr 26 12:55:40.743535 dhclient: For info, please visit https://www.isc.org/software/dhcp/
  52. Apr 26 12:55:40.743540 dhclient:
  53. Apr 26 12:55:40.743544 networking[317]: Configuring network interfaces...Internet Systems Consortium DHCP Client 4.3.1
  54. Apr 26 12:55:40.743550 networking[317]: Copyright 2004-2014 Internet Systems Consortium.
  55. Apr 26 12:55:40.743554 networking[317]: All rights reserved.
  56. Apr 26 12:55:40.743559 networking[317]: For info, please visit https://www.isc.org/software/dhcp/
  57. Apr 26 12:55:40.743563 dhclient: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system
  58. Apr 26 12:55:40.743568 networking[317]: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system
  59. Apr 26 12:55:40.743575 dhclient: Listening on LPF/eth0/d4:f5:27:e5:48:61
  60. Apr 26 12:55:40.743580 dhclient: Sending on   LPF/eth0/d4:f5:27:e5:48:61
  61. Apr 26 12:55:40.743584 dhclient: Sending on   Socket/fallback
  62. Apr 26 12:55:40.743589 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
  63. Apr 26 12:55:40.743593 networking[317]: Listening on LPF/eth0/d4:f5:27:e5:48:61
  64. Apr 26 12:55:40.743598 networking[317]: Sending on   LPF/eth0/d4:f5:27:e5:48:61
  65. Apr 26 12:55:40.743602 networking[317]: Sending on   Socket/fallback
  66. Apr 26 12:55:40.743607 networking[317]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 5
  67. Apr 26 12:55:40.743613 dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 10
  68. Apr 26 12:55:40.743618 networking[317]: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 10
  69. Apr 26 12:55:40.743623 dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67
  70. Apr 26 12:55:40.743627 dhclient: DHCPOFFER from 192.168.19.1
  71. Apr 26 12:55:40.743632 networking[317]: DHCPREQUEST on eth0 to 255.255.255.255 port 67
  72. Apr 26 12:55:40.743636 networking[317]: DHCPOFFER from 192.168.19.1
  73. Apr 26 12:55:40.743641 dhclient: DHCPACK from 192.168.19.1
  74. Apr 26 12:55:40.743645 networking[317]: DHCPACK from 192.168.19.1
  75. 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
  76. Apr 26 12:55:40.743656 dhclient: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system
  77. Apr 26 12:55:40.743661 dhclient: bound to 192.168.19.108 -- renewal in 359564 seconds.
  78. Apr 26 12:55:40.743666 networking[317]: can't create /var/lib/dhcp/dhclient.eth0.leases: Read-only file system
  79. Apr 26 12:55:40.743670 networking[317]: bound to 192.168.19.108 -- renewal in 359564 seconds.
  80. Apr 26 12:55:40.743675 networking[317]: start exec /usr/bin/setDnsHostname for eth0 from /etc/network/if-up.d/setHostname
  81. Apr 26 12:55:40.743679 networking[317]: Cannot find device "eth3"
  82. Apr 26 12:55:40.743684 networking[317]: Failed to bring up eth3.
  83. Apr 26 12:55:40.743690 networking[317]: done.
  84. Apr 26 12:55:40.743695 systemd[1]: Started LSB: Raise network interfaces..
  85. Apr 26 12:55:40.743699 systemd[1]: Starting Network.
  86. Apr 26 12:55:40.743704 systemd[1]: Reached target Network.
  87. Apr 26 12:55:40.743708 systemd[1]: Starting Network is Online.
  88. Apr 26 12:55:40.743713 systemd[1]: Reached target Network is Online.
  89. Apr 26 12:55:40.743717 systemd[1]: Starting System Initialization.
  90. Apr 26 12:55:40.743722 systemd[1]: Reached target System Initialization.
  91. Apr 26 12:55:40.743728 systemd[1]: Starting Daily Cleanup of Temporary Directories.
  92. Apr 26 12:55:40.743732 systemd[1]: Started Daily Cleanup of Temporary Directories.
  93. Apr 26 12:55:40.743737 systemd[1]: Starting Timers.
  94. Apr 26 12:55:40.743741 systemd[1]: Reached target Timers.
  95. Apr 26 12:55:40.743746 systemd[1]: Starting Basic System.
  96. Apr 26 12:55:40.743750 systemd[1]: Reached target Basic System.
  97. Apr 26 12:55:40.743754 systemd[1]: Starting OpenBSD Secure Shell server...
  98. Apr 26 12:55:40.743759 systemd[1]: Started OpenBSD Secure Shell server.
  99. Apr 26 12:55:40.743765 systemd[1]: Starting Lighttpd Daemon...
  100. Apr 26 12:55:40.743769 systemd[1]: Starting Pure-FTPd FTP server...
  101. Apr 26 12:55:40.743774 systemd[1]: Starting Regular background program processing daemon...
  102. Apr 26 12:55:40.743778 systemd[1]: Started Regular background program processing daemon.
  103. Apr 26 12:55:40.743783 systemd[1]: Starting Installation-script for P@ckControl...
  104. Apr 26 12:55:40.743787 systemd[1]: Starting Internet superserver...
  105. Apr 26 12:55:40.743792 cron[545]: (CRON) INFO (pidfile fd = 3)
  106. Apr 26 12:55:40.743796 systemd[1]: Started Internet superserver.
  107. Apr 26 12:55:40.743801 systemd[1]: Starting Initialize hardware monitoring sensors...
  108. Apr 26 12:55:40.743807 systemd[1]: Starting Pure-FTPd FTP server...
  109. Apr 26 12:55:40.743812 cron[545]: (CRON) INFO (Running @reboot jobs)
  110. Apr 26 12:55:40.743816 systemd[1]: Starting /etc/rc.local Compatibility...
  111. Apr 26 12:55:40.743821 systemd[1]: Starting getty on tty2-tty6 if dbus and logind are not available...
  112. Apr 26 12:55:40.743825 systemd[1]: Started Login Service.
  113. Apr 26 12:55:40.743830 systemd[1]: Starting LSB: No Display Manager...
  114. Apr 26 12:55:40.743834 systemd[1]: Starting LSB: Start NTP daemon...
  115. Apr 26 12:55:40.743839 systemd[1]: Starting LSB: Start/stop sysstat's sadc...
  116. Apr 26 12:55:40.743845 systemd[1]: Starting System Logging Service...
  117. Apr 26 12:55:40.743849 systemd[1]: Starting Permit User Sessions...
  118. Apr 26 12:55:40.743854 systemd[1]: PID file /var/run/pure-uploadscript.pid not readable (yet?) after start.
  119. Apr 26 12:55:40.742390 sshd[542]: Server listening on 0.0.0.0 port 22.
  120. Apr 26 12:55:40.742737 sshd[542]: Server listening on :: port 22.
  121. Apr 26 12:55:40.750974 systemd[1]: Started Installation-script for P@ckControl.
  122. Apr 26 12:55:40.756090 systemd[1]: PID file /var/run/pure-ftpd.pid not readable (yet?) after start.
  123. Apr 26 12:55:40.759258 sensors[577]: coretemp-isa-0000
  124. Apr 26 12:55:40.759516 sensors[577]: Adapter: ISA adapter
  125. Apr 26 12:55:40.759724 sensors[577]: Physical id 0:  +36.0 C  (high = +100.0 C, crit = +100.0 C)
  126. Apr 26 12:55:40.759993 sensors[577]: Core 0:         +36.0 C  (high = +100.0 C, crit = +100.0 C)
  127. Apr 26 12:55:40.760201 sensors[577]: Core 1:         +35.0 C  (high = +100.0 C, crit = +100.0 C)
  128. Apr 26 12:55:40.769973 systemd[1]: Started /etc/rc.local Compatibility.
  129. Apr 26 12:55:40.770284 systemd[1]: Started LSB: Start/stop sysstat's sadc.
  130. Apr 26 12:55:40.770552 systemd[1]: Started Permit User Sessions.
  131. Apr 26 12:55:40.772067 systemd[1]: Started Initialize hardware monitoring sensors.
  132. Apr 26 12:55:40.774597 systemd[1]: Started System Logging Service.
  133. Apr 26 12:55:40.774866 systemd[1]: Received SIGRTMIN+21 from PID 135 (plymouthd).
  134. Apr 26 12:55:40.778391 systemd[1]: Started LSB: No Display Manager.
  135. Apr 26 12:55:40.779564 nodm[584]: starting nodm
  136. Apr 26 12:55:40.789676 ntpd[591]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:48:00 UTC 2016 (1)
  137. Apr 26 12:55:40.790578 ntpd[592]: proto: precision = 0.561 usec
  138. Apr 26 12:55:40.791290 ntp[555]: Starting NTP server: ntpd.
  139. Apr 26 12:55:40.791571 ntpd[592]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
  140. Apr 26 12:55:40.792187 systemd[1]: Started LSB: Start NTP daemon.
  141. Apr 26 12:55:40.797897 ntpd[592]: Listen and drop on 1 v6wildcard :: UDP 123
  142. Apr 26 12:55:40.798296 ntpd[592]: Listen normally on 2 lo 127.0.0.1 UDP 123
  143. Apr 26 12:55:40.798539 ntpd[592]: Listen normally on 3 eth1 192.168.1.102 UDP 123
  144. Apr 26 12:55:40.798766 ntpd[592]: Listen normally on 4 eth1:1 192.168.1.193 UDP 123
  145. Apr 26 12:55:40.799064 ntpd[592]: Listen normally on 5 eth2 169.254.253.254 UDP 123
  146. Apr 26 12:55:40.799307 ntpd[592]: Listen normally on 6 eth0 192.168.19.108 UDP 123
  147. Apr 26 12:55:40.799525 ntpd[592]: Listen normally on 7 lo ::1 UDP 123
  148. Apr 26 12:55:40.799750 ntpd[592]: Listen normally on 8 eth0 2a03:4b22:6071:5700:d6f5:27ff:fee5:4861 UDP 123
  149. Apr 26 12:55:40.800027 ntpd[592]: Listen normally on 9 eth0 fe80::d6f5:27ff:fee5:4861 UDP 123
  150. Apr 26 12:55:40.800263 ntpd[592]: peers refreshed
  151. Apr 26 12:55:40.800477 ntpd[592]: Listening on routing socket on fd #26 for interface updates
  152. Apr 26 12:55:40.809309 systemd[1]: Starting Wait for Plymouth Boot Screen to Quit...
  153. Apr 26 12:55:40.815885 systemd[1]: Starting Terminate Plymouth Boot Screen...
  154. Apr 26 12:55:40.818522 systemd[1]: Starting pcupdate for P@ckControl...
  155. Apr 26 12:55:40.820564 lighttpd[543]: Syntax OK
  156. Apr 26 12:55:40.822621 systemd[1]: Started Lighttpd Daemon.
  157. Apr 26 12:55:40.824303 systemd[1]: Started Wait for Plymouth Boot Screen to Quit.
  158. Apr 26 12:55:40.835974 systemd[1]: Started Terminate Plymouth Boot Screen.
  159. Apr 26 12:55:40.840250 fortytwo.sh[596]: SIMATIC IPC427E
  160. Apr 26 12:55:40.840934 fortytwo.sh[596]: use: /usr/etc/udev/70-persistent-net_427E for Board SIMATIC IPC427E!!
  161. Apr 26 12:55:40.845380 fortytwo.sh[596]: mkdir: cannot create directory '/var/log/lighttpd': File exists
  162. Apr 26 12:55:40.849017 fortytwo.sh[596]: last boot...succeeded
  163. Apr 26 12:55:40.861188 fortytwo.sh[596]: No recalibration is necessary!
  164. Apr 26 12:55:40.862034 fortytwo.sh[596]: Calibration file remains untouched.
  165. Apr 26 12:55:40.867093 fortytwo.sh[596]: TestHome.RDF filesize seems to be ok - file remains untouched
  166. Apr 26 12:55:40.872781 systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
  167. Apr 26 12:55:40.874825 systemd[1]: Starting Getty on tty6...
  168. Apr 26 12:55:40.879641 fortytwo.sh[596]: check for prepared updates...no updates available
  169. Apr 26 12:55:40.880384 systemd[1]: Started Getty on tty6.
  170. Apr 26 12:55:40.881121 systemd[1]: Starting Getty on tty5...
  171. Apr 26 12:55:40.884484 fortytwo.sh[596]: Check: /opt/update/rovema/lib
  172. Apr 26 12:55:40.887841 fortytwo.sh[596]: no files to update available in /opt/update/rovema/lib
  173. Apr 26 12:55:40.888314 fortytwo.sh[596]: Check: /opt/update/rovema/rds
  174. Apr 26 12:55:40.891491 fortytwo.sh[596]: no files to update available in /opt/update/rovema/rds
  175. Apr 26 12:55:40.891910 fortytwo.sh[596]: Check: /opt/update/rovema/plc
  176. Apr 26 12:55:40.895135 fortytwo.sh[596]: no files to update available in /opt/update/rovema/plc
  177. Apr 26 12:55:40.895566 fortytwo.sh[596]: Check: /opt/update/rovema/adapter
  178. Apr 26 12:55:40.898655 fortytwo.sh[596]: no files to update available in /opt/update/rovema/adapter
  179. Apr 26 12:55:40.899095 fortytwo.sh[596]: Check: /opt/update/rovema/calcmod
  180. Apr 26 12:55:40.902215 fortytwo.sh[596]: no files to update available in /opt/update/rovema/calcmod
  181. Apr 26 12:55:40.903954 systemd[1]: Started Getty on tty5.
  182. Apr 26 12:55:40.904244 systemd[1]: Starting Getty on tty4...
  183. Apr 26 12:55:40.911027 fortytwo.sh[596]: Check: /opt/update/rovema/javis
  184. Apr 26 12:55:40.917383 fortytwo.sh[596]: no files to update available in /opt/update/rovema/javis
  185. Apr 26 12:55:40.920123 fortytwo.sh[596]: cleaning update folder...done
  186. Apr 26 12:55:40.922612 fortytwo.sh[596]: restoring update file structure...done
  187. Apr 26 12:55:40.923528 systemd[1]: Started Getty on tty4.
  188. Apr 26 12:55:40.923891 systemd[1]: Starting Getty on tty3...
  189. Apr 26 12:55:40.928354 systemd[1]: Started Getty on tty3.
  190. Apr 26 12:55:40.928619 systemd[1]: Starting Getty on tty2...
  191. Apr 26 12:55:40.929154 nodm[584]: X server 590 quit with status 1
  192. Apr 26 12:55:40.930140 systemd[1]: Started Getty on tty2.
  193. Apr 26 12:55:40.930503 systemd[1]: Starting Getty on tty1...
  194. Apr 26 12:55:40.931987 systemd[1]: Started Getty on tty1.
  195. Apr 26 12:55:40.932511 systemd[1]: Starting Login Prompts.
  196. Apr 26 12:55:40.932811 systemd[1]: Reached target Login Prompts.
  197. Apr 26 12:55:41.682748 systemd[1]: Started Pure-FTPd FTP server.
  198. Apr 26 12:55:41.683354 systemd[1]: Started Pure-FTPd FTP server.
  199. Apr 26 12:55:41.722632 fortytwo.sh[596]: chown: changing ownership of '/opt/backup/lost+found': Read-only file system
  200. Apr 26 12:55:41.723082 fortytwo.sh[596]: chown: changing ownership of '/opt/backup/packcontrol.tar.gz': Read-only file system
  201. Apr 26 12:55:41.723337 fortytwo.sh[596]: chown: changing ownership of '/opt/backup': Read-only file system
  202. Apr 26 12:55:41.899869 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup': Read-only file system
  203. Apr 26 12:55:41.900276 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup/lost+found': Read-only file system
  204. Apr 26 12:55:41.900520 fortytwo.sh[596]: chmod: changing permissions of '/opt/backup/packcontrol.tar.gz': Read-only file system
  205. Apr 26 12:55:41.905654 fortytwo.sh[596]: done(ext3)
  206. Apr 26 12:55:41.906786 systemd[1]: Started pcupdate for P@ckControl.
  207. Apr 26 12:55:41.912428 systemd[1]: Starting Start the RDS, REUS and other deamons of P@ckControl...
  208. Apr 26 12:55:41.912840 systemd[1]: Starting Adapter (HMI-Adapter)...
  209. Apr 26 12:55:41.914716 systemd[1]: Started Adapter (HMI-Adapter).
  210. Apr 26 12:55:41.917099 systemd[1]: Starting Synchronize HwClock with system clock...
  211. Apr 26 12:55:41.920750 systemd[1]: Started Synchronize HwClock with system clock.
  212. Apr 26 12:55:41.928239 synchwclock.sh[667]: unsynchronised
  213. Apr 26 12:55:41.928581 synchwclock.sh[667]: time server re-starting
  214. Apr 26 12:55:41.928814 synchwclock.sh[667]: polling server every 8 s
  215. Apr 26 12:55:41.938272 startadapter.sh[665]: Set NAT-address of 192.168.1.194 to 192.168.108.194.
  216. Apr 26 12:56:08.474996 systemd[1]: Time has been changed
  217. Apr 26 12:56:08.475849 synchwclock.sh[667]: Tue Apr 26 12:56:08 CEST 2022
  218. Apr 26 12:56:08.476507 synchwclock.sh[667]: rdate: adjust local clock by 26.519399 seconds
  219. Apr 26 12:56:08.491738 startadapter.sh[665]: Set NAT-address of 192.168.1.195 to 192.168.108.195.
  220. Apr 26 12:56:08.497808 startadapter.sh[665]: Set NAT-address of 192.168.1.196 to 192.168.108.196.
  221. Apr 26 12:56:08.504525 startadapter.sh[665]: Set NAT-address of 192.168.1.197 to 192.168.108.197.
  222. Apr 26 12:56:08.511683 startadapter.sh[665]: Set NAT-address of 192.168.1.198 to 192.168.108.198.
  223. Apr 26 12:56:08.517918 startadapter.sh[665]: Set NAT-address of 192.168.1.199 to 192.168.108.199.
  224. Apr 26 12:56:08.523939 startadapter.sh[665]: Set NAT-address of 192.168.1.200 to 192.168.108.200.
  225. Apr 26 12:56:08.529963 startadapter.sh[665]: Set NAT-address of 192.168.1.201 to 192.168.108.201.
  226. Apr 26 12:56:08.536475 startadapter.sh[665]: Set NAT-address of 192.168.1.202 to 192.168.108.202.
  227. Apr 26 12:56:08.542595 startadapter.sh[665]: Set NAT-address of 192.168.1.203 to 192.168.108.203.
  228. Apr 26 12:56:08.549108 startadapter.sh[665]: Set NAT-address of 192.168.1.204 to 192.168.108.204.
  229. Apr 26 12:56:08.560945 startadapter.sh[665]: Set NAT-address of 192.168.1.205 to 192.168.108.205.
  230. Apr 26 12:56:08.567236 startadapter.sh[665]: Set NAT-address of 192.168.1.206 to 192.168.108.206.
  231. Apr 26 12:56:08.737130 P@ck-Control[681]:  [681 RdsMain]; [NOTICE] ++++++Initializing Rovema Data Server++++++
  232. Apr 26 12:56:08.738209 P@ck-Control[681]:  [681 RdsMain]; [DEBUG] parent exists already; PID of daemon is 773
  233. Apr 26 12:56:08.738719 P@ck-Control[773]:  [773 rds_daemonize]; [DEBUG] Daemon created
  234. 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,
  235. 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,
  236. Apr 26 12:56:08.740162 P@ck-Control[773]:  [773 rds_daemonize]; [DEBUG] make FIFO /tmp/rdsCmd
  237. 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'
  238. 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!
  239. Apr 26 12:56:08.740929 P@ck-Control[773]:  [773 init_rds]; [INFO] Working in directory '/opt/rovema/rds'
  240. Apr 26 12:56:08.741141 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Real directory is '/opt/rovema/rds'!
  241. Apr 26 12:56:08.741581 P@ck-Control[773]:  [773 init_rds]; [INFO] Registering standard signal handler (all)
  242. 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
  243. Apr 26 12:56:08.742385 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Start hostBaseInit()!!
  244. Apr 26 12:56:08.743053 P@ck-Control[773]:  [776 TicTask]; [DEBUG] fTicTask: Set CPU Affinity to 0x2; 0x0;
  245. Apr 26 12:56:08.743711 P@ck-Control[773]:  [773 init_rds]; [DEBUG] hostBaseInit: at 26.04.2022 12:56:08.742
  246. 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
  247. Apr 26 12:56:08.744828 P@ck-Control[773]:  [778 powerFailDetection]; [DEBUG] C_PowerFail::threadEntry 0xb21004c0!!
  248. Apr 26 12:56:08.746719 P@ck-Control[773]: Power: match_line( /sys/class/uio/uio0/name, uio_nvram) not found 2
  249. Apr 26 12:56:08.747067 P@ck-Control[773]: Power: match_line( /sys/class/uio/uio0/name, simatic_ipc_nvram) not found 2
  250. Apr 26 12:56:08.747351 P@ck-Control[773]:  [778 powerFailDetection]; [INFO] no Janich & Klass - PC-S3 board_version 'RS-AA#012l' found
  251. Apr 26 12:56:08.749671 P@ck-Control[773]:  [778 powerFailDetection]; [DEBUG] C_PowerFail::thread uio not found -1!!
  252. Apr 26 12:56:08.751238 P@ck-Control[773]:  [776 TicTask]; [DEBUG] C_PowerFail() constructor ready 0xb21004c0!
  253. Apr 26 12:56:08.752163 P@ck-Control[773]:  [773 init_rds]; [DEBUG] BackUpBasis = '/opt/rovema/dailybackup/Tuesday/04.'
  254. Apr 26 12:56:08.753840 P@ck-Control[773]:  [773 init_rds]; [DEBUG] rename error.log.
  255. Apr 26 12:56:08.754086 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Rds - constructor!
  256. Apr 26 12:56:08.754951 P@ck-Control[773]:  [782 tLgLst2File]; [DEBUG] RDS: start ll_task_writeInFile
  257. Apr 26 12:56:08.755195 P@ck-Control[773]:  [783 rdsPollProc]; [DEBUG] C_RdsDataProcess::procPollTask(): cyclicCallSync = 0
  258. Apr 26 12:56:08.755512 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Use SRAM 4!
  259. Apr 26 12:56:08.755736 P@ck-Control[773]:  [773 init_rds]; [INFO] RDS: 1. NvRam found at /root/nvram0, size = 262144
  260. 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'
  261. Apr 26 12:56:08.757341 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_RdsSram::C_RdsSram from 0xb1d4d008 to 0xb1d8d008 ############
  262. Apr 26 12:56:08.757624 P@ck-Control[773]:  [773 init_rds]; [NOTICE] Load RDF from file: 'TestHome.RDF'.
  263. 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
  264. Apr 26 12:56:08.766743 systemd[1]: Started Start the RDS, REUS and other deamons of P@ckControl.
  265. Apr 26 12:56:08.767067 systemd[1]: Starting Multi-User System.
  266. Apr 26 12:56:08.767396 systemd[1]: Reached target Multi-User System.
  267. Apr 26 12:56:08.771735 systemd[1]: Starting Start the Javis the Rovama HMI...
  268. Apr 26 12:56:08.774923 systemd[1]: Started Start the Javis the Rovama HMI.
  269. Apr 26 12:56:08.775376 systemd[1]: Starting Graphical Interface.
  270. Apr 26 12:56:08.775718 systemd[1]: Reached target Graphical Interface.
  271. Apr 26 12:56:08.776020 systemd[1]: Starting Starts the touchscreen recalibration if neccessary...
  272. Apr 26 12:56:08.777141 systemd[1]: Started Starts the touchscreen recalibration if neccessary.
  273. Apr 26 12:56:08.777533 systemd[1]: Starting Update UTMP about System Runlevel Changes...
  274. Apr 26 12:56:08.786270 systemd[1]: Started Update UTMP about System Runlevel Changes.
  275. Apr 26 12:56:08.798802 systemd[1]: Startup finished in 2.020s (kernel) + 10.726s (userspace) = 12.747s.
  276. Apr 26 12:56:08.822102 systemd[1]: Stopping Start the Javis the Rovama HMI...
  277. Apr 26 12:56:08.825901 systemd[1]: Stopped Start the Javis the Rovama HMI.
  278. Apr 26 12:56:08.835738 recalibrate.sh[790]: Unable to connect to X server
  279. Apr 26 12:56:08.886499 systemd[1]: Starting Start the Javis the Rovama HMI...
  280. Apr 26 12:56:08.891061 systemd[1]: Started Start the Javis the Rovama HMI.
  281. Apr 26 12:56:08.907865 REUS[786]:  [786 ReusMain]; [INFO] Trying to connect to server..
  282. Apr 26 12:56:08.908368 start-rds.sh[664]: Start RDC REUS to 127.0.0.1
  283. Apr 26 12:56:08.908877 REUS[786]:  [813 tRdcBaREUS]; [NOTICE] Start RDC: REUS
  284. Apr 26 12:56:09.115472 start-rds.sh[664]: tcpdump: eth3: No such device exists
  285. Apr 26 12:56:09.115599 start-rds.sh[664]: (SIOCGIFHWADDR: No such device)
  286. Apr 26 12:56:09.156615 start-rds.sh[664]: tcpdump: eth3: No such device exists
  287. Apr 26 12:56:09.156814 start-rds.sh[664]: (SIOCGIFHWADDR: No such device)
  288. Apr 26 12:56:09.244130 P@ck-Control[773]:  [778 powerFailDetection]; [WARNING] RdsSram C_RdsSram::copyToFileMap finished!
  289. Apr 26 12:56:09.258873 P@ck-Control[773]:  [773 init_rds]; [INFO] NVRAM '/nvram0' is used, remanent data are unimpaired!
  290. Apr 26 12:56:09.358942 start-rds.sh[664]: /sys/kernel/debug/tracing/events/sched/filter
  291. Apr 26 12:56:09.359077 start-rds.sh[664]: /sys/kernel/debug/tracing/events/*/sched/filter
  292. 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.
  293. Apr 26 12:56:09.589227 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.initDisplay(Native Method)
  294. Apr 26 12:56:09.589736 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.access$200(X11GraphicsEnvironment.java:65)
  295. Apr 26 12:56:09.590394 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment$1.run(X11GraphicsEnvironment.java:110)
  296. Apr 26 12:56:09.590830 start-javis.sh[808]: at java.security.AccessController.doPrivileged(Native Method)
  297. Apr 26 12:56:09.591216 start-javis.sh[808]: at sun.awt.X11GraphicsEnvironment.<clinit>(X11GraphicsEnvironment.java:74)
  298. Apr 26 12:56:09.591777 start-javis.sh[808]: at java.lang.Class.forName0(Native Method)
  299. Apr 26 12:56:09.592158 start-javis.sh[808]: at java.lang.Class.forName(Class.java:195)
  300. Apr 26 12:56:09.592794 start-javis.sh[808]: at java.awt.GraphicsEnvironment.createGE(GraphicsEnvironment.java:102)
  301. Apr 26 12:56:09.593215 start-javis.sh[808]: at java.awt.GraphicsEnvironment.getLocalGraphicsEnvironment(GraphicsEnvironment.java:81)
  302. Apr 26 12:56:09.594412 start-javis.sh[808]: at java.awt.Window.initGC(Window.java:467)
  303. Apr 26 12:56:09.594879 start-javis.sh[808]: at java.awt.Window.init(Window.java:487)
  304. Apr 26 12:56:09.595256 start-javis.sh[808]: at java.awt.Window.<init>(Window.java:536)
  305. Apr 26 12:56:09.595683 start-javis.sh[808]: at java.awt.Frame.<init>(Frame.java:420)
  306. Apr 26 12:56:09.596054 start-javis.sh[808]: at javax.swing.JFrame.<init>(JFrame.java:218)
  307. Apr 26 12:56:09.596438 start-javis.sh[808]: at javisWindows.JavisStartup.<init>(JavisStartup.java:186)
  308. Apr 26 12:56:09.596828 start-javis.sh[808]: at Javis.main(Javis.java:45)
  309. Apr 26 12:56:09.601687 start-javis.sh[808]: javisReturn 1
  310. Apr 26 12:56:09.603827 start-javis.sh[808]: End of script: start-Javis.sh
  311. Apr 26 12:56:09.703966 startadapter.sh[665]: > ro-adapter@2.3.4 start /opt/rovema/adapter
  312. 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"
  313. Apr 26 12:56:09.912410 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_RdsDataStream::readStream: 18265 lines read!
  314. Apr 26 12:56:09.925079 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RDS: 0 ric-files found.
  315. 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
  316. Apr 26 12:56:10.421262 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS writeStream '/opt/rovema/rds/TestHome.RDF.inwork' start
  317. Apr 26 12:56:10.779564 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS writeStream OK
  318. Apr 26 12:56:10.780151 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured
  319. Apr 26 12:56:10.780526 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured
  320. Apr 26 12:56:10.850821 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured
  321. Apr 26 12:56:10.932267 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured
  322. Apr 26 12:56:10.932687 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured
  323. Apr 26 12:56:10.962269 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured
  324. Apr 26 12:56:10.976374 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS lL_Startup begin
  325. Apr 26 12:56:10.978082 P@ck-Control[773]:  [773 init_rds]; [DEBUG] ll_RestoreFromFile 'System.RDS.alarmList' start piDataFix 0x953e248
  326. Apr 26 12:56:10.979862 P@ck-Control[773]:  [773 init_rds]; [DEBUG] ll_RestoreFromFile 'System.RDS.alarmList' finish piDataFix 0x953e248
  327. Apr 26 12:56:10.980434 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS link begin
  328. Apr 26 12:56:10.982739 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS processStartup begin
  329. Apr 26 12:56:10.983258 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS m_poOscilloscopeList begin
  330. Apr 26 12:56:10.984496 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS createConnections begin
  331. Apr 26 12:56:10.987181 P@ck-Control[773]:  [773 init_rds]; [DEBUG] RDS -- start RMA-Server ##
  332. Apr 26 12:56:10.988969 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RMA-logging is now 'Off'!
  333. 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!
  334. Apr 26 12:56:10.992990 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RdsExecutableList  4 start; 0 startUp !
  335. Apr 26 12:56:10.994095 P@ck-Control[773]:  [773 init_rds]; [NOTICE] Init MinMaxModules
  336. Apr 26 12:56:10.994933 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Finish RDC-Client!! : MinMaxModul
  337. Apr 26 12:56:11.015580 P@ck-Control[773]:  [855 infoMinMaxModul]; [INFO] Finish Informtask of MinMaxModul -1- HostCPU(127.0.0.1):
  338. Apr 26 12:56:11.116229 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Delete C_RdsClientLocal of 'MinMaxModul'
  339. Apr 26 12:56:11.117149 P@ck-Control[773]:  [773 init_rds]; [DEBUG] systemTicInit cycle: 1000000ns AxisTask: 10000000ns
  340. Apr 26 12:56:11.117878 P@ck-Control[773]:  [773 init_rds]; [DEBUG] IPSettings Configuration Interface (Constructor)
  341. Apr 26 12:56:11.118405 P@ck-Control[773]:  [773 init_rds]; [DEBUG] IP Configuration Interface (Constructor)
  342. 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!
  343. Apr 26 12:56:11.120253 P@ck-Control[773]:  [773 init_rds]; [DEBUG] IP Configuration Interface (Constructor)
  344. 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!
  345. Apr 26 12:56:11.120929 P@ck-Control[773]:  [773 init_rds]; [INFO] Initialize IP Configuration Module
  346. Apr 26 12:56:11.122878 P@ck-Control[773]:  [773 init_rds]; [INFO] IP Settings interface initialized
  347. Apr 26 12:56:11.123179 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_RdsExecutable::execute : '/opt/rovema/system/timezone.sh System&'
  348. Apr 26 12:56:11.227281 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_RdsExecutable::execute : '/opt/rovema/system/getThinClientData.sh System.Version&'
  349. Apr 26 12:56:11.332477 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RdsExecutableList::start 4 started
  350. Apr 26 12:56:11.332937 P@ck-Control[773]:  [773 init_rds]; [NOTICE] Start initSercos3Driver at 12:56:11.331
  351. Apr 26 12:56:11.334598 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Sercos3Driver::getRedundancy() is 0 (line 381)
  352. Apr 26 12:56:11.334869 P@ck-Control[773]:  [773 init_rds]; [INFO] C_Sercos3Driver(): SoftMaster is activ!!
  353. 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
  354. Apr 26 12:56:11.335462 P@ck-Control[773]:  [773 init_rds]; [INFO] Init Sercos-SoftMaster!!
  355. Apr 26 12:56:11.335809 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 0 to 'eno2'.
  356. 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'.
  357. Apr 26 12:56:11.336265 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 0 to 'eth2'.
  358. 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'.
  359. Apr 26 12:56:11.337485 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (746):RTLX_SetInterfacName of instance 1 to 'eth3'.
  360. 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'.
  361. Apr 26 12:56:11.338084 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (839):RTLX_GetNicName of instance 0 'eth2'.
  362. Apr 26 12:56:11.338371 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (1456):RTLX_InitNicTimedTransmission() i 0, 'eth2'
  363. Apr 26 12:56:11.338929 P@ck-Control[773]:  [773 init_rds]; [NOTICE] RTLX (839):RTLX_GetNicName of instance 0 'eth2'.
  364. 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¶ÿÿÿÿÿÿÿÿ'.
  365. 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'
  366. 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'
  367. 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
  368. 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
  369. 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.
  370. 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
  371. 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
  372. 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
  373. 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
  374. 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;
  375. 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;
  376. 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
  377. Apr 26 12:56:11.345630 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_SercosDriverBase::initSercosSoftMaster finished CP -1 .
  378. Apr 26 12:56:11.346658 P@ck-Control[773]: INFO: sercos initialize ucc of 0!
  379. Apr 26 12:56:11.346946 P@ck-Control[773]: INFO: sercos ucc MAC of 'serc0' to 06:f5:27:e5:a8:90!
  380. Apr 26 12:56:11.352358 P@ck-Control[773]: INFO: sercos ucc of 'serc0' is initialized successfully fd: 21!
  381. Apr 26 12:56:11.352730 P@ck-Control[773]: sercos ucc of 'serc0' initialized successfully!
  382. Apr 26 12:56:11.354147 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_SercosDriverBase::SercosSetupUCC done m_UccFd 21 .
  383. Apr 26 12:56:11.354523 P@ck-Control[773]: setUCCAddr HW-ADDR:  06-f5-27-e5-a8-90;
  384. Apr 26 12:56:11.354800 P@ck-Control[773]: setUCCAddr setup ifr done
  385. Apr 26 12:56:11.355057 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Sercos3Driver: Init Sercos3Slaves
  386. 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
  387. 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
  388. 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
  389. 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]
  390. 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]
  391. 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]
  392. Apr 26 12:56:11.380622 P@ck-Control[773]:  [773 init_rds]; [INFO] C_Sercos3Driver 2 Slaves configured!
  393. Apr 26 12:56:11.380894 P@ck-Control[773]:  [948 rds_plc]; [INFO] plcInit -> BootfileDirectory = '/opt/rovema/plc'; sFileDirectory = '/opt/rovema/plc'
  394. Apr 26 12:56:11.381166 P@ck-Control[773]:  [773 init_rds]; [INFO] WaitForStartup done
  395. Apr 26 12:56:11.381457 P@ck-Control[773]:  [953 SercosCyclicBus]; [DEBUG] C_Sercos3Driver::cycleThread: Set CPU Affinity to 0x2; 0x0;
  396. Apr 26 12:56:11.381726 P@ck-Control[773]:  [953 SercosCyclicBus]; [NOTICE] RTLX (283):Successfully started NIC-timed alarm thread
  397. Apr 26 12:56:11.382678 P@ck-Control[773]:  [954 SercosService]; [INFO] C_Sercos3Driver::_sercosServiceThread() started
  398. Apr 26 12:56:11.383001 P@ck-Control[773]:  [952 SercosInit]; [DEBUG] C_Sercos3Driver::initThread(): set CP 2! (m_bAutoConfiguration = 0)
  399. Apr 26 12:56:11.383290 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseNotInit...
  400. 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
  401. Apr 26 12:56:11.385030 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseHardwareInit...
  402. Apr 26 12:56:11.385306 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDeb start!
  403. Apr 26 12:56:11.385625 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp();wait for m_ReadyForStartup ! Ok 1
  404. Apr 26 12:56:11.390562 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcMem start!
  405. Apr 26 12:56:11.400771 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcComm start!
  406. Apr 26 12:56:11.410979 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcRoot start!
  407. Apr 26 12:56:11.421184 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcSvis start!
  408. Apr 26 12:56:11.431373 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run CritAnal start!
  409. Apr 26 12:56:11.442098 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run DChanges start!
  410. Apr 26 12:56:11.451726 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] ++ firmwareSystickInstall called from PLC t2sec 1000
  411. Apr 26 12:56:11.452253 P@ck-Control[773]:  [948 rds_plc]; [INFO] Start initModBusDriver at 12:56:11.410
  412. Apr 26 12:56:11.584990 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCommunicationParameter...
  413. Apr 26 12:56:11.586231 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetNrtMode...
  414. Apr 26 12:56:11.587127 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseInitConfigStruct...,00
  415. Apr 26 12:56:11.589057 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase0...; cycle 110
  416. 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
  417. Apr 26 12:56:11.909371 P@ck-Control[773]:  [852 tRmaServer]; [DEBUG] C_SocketDataChannel::AcceptConnectionRequest - Set Extended data-length in server!
  418. Apr 26 12:56:11.983268 P@ck-Control[773]:  [984 infoModBusDriver]; [INFO] Finish Informtask of ModBusDriver -1- HostCPU(127.0.0.1):
  419. 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
  420. Apr 26 12:56:12.010349 P@ck-Control[773]:  [1021 rmaSrSes01]; [DEBUG] C_SocketDataChannel::AcceptConnectionRequest - Set Extended data-length in server!
  421. Apr 26 12:56:12.083291 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] Delete C_RdsClientLocal of 'ModBusDriver'
  422. Apr 26 12:56:12.083780 P@ck-Control[773]:  [948 rds_plc]; [INFO] initModBusDriver no ModBus found
  423. Apr 26 12:56:12.084039 P@ck-Control[773]:  [948 rds_plc]; [INFO] Start initNetXDriver at 12:56:11.726
  424. Apr 26 12:56:12.114544 P@ck-Control[773]:  [1024 infoNetXDriver]; [INFO] Finish Informtask of NetXDriver -1- HostCPU(127.0.0.1):
  425. Apr 26 12:56:12.214553 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] Delete C_RdsClientLocal of 'NetXDriver'
  426. Apr 26 12:56:12.215000 P@ck-Control[773]:  [948 rds_plc]; [INFO] initNetXDriver no NetX found
  427. Apr 26 12:56:12.216908 P@ck-Control[773]:  [1029 plcEnableWD]; [INFO] plcWatchdogEnableTask started
  428. Apr 26 12:56:12.217289 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv0 start!
  429. Apr 26 12:56:12.227305 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv1 start!
  430. Apr 26 12:56:12.230373 REUS[786]:  [786 ReusMain]; [NOTICE] REUS connected
  431. 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
  432. Apr 26 12:56:12.237566 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv2 start!
  433. Apr 26 12:56:12.247745 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv3 start!
  434. Apr 26 12:56:12.257977 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv4 start!
  435. Apr 26 12:56:12.268219 P@ck-Control[773]:  [948 rds_plc]; [DEBUG] sys_task_run tpcDrv5 start!
  436. Apr 26 12:56:12.278452 P@ck-Control[773]:  [948 rds_plc]; [INFO] ProConOS Linux V4.2.0245
  437. Apr 26 12:56:12.278861 P@ck-Control[773]:  [948 rds_plc]; [INFO] --------   (c) 2013 KW-Software GmbH   D-32657 Lemgo
  438. Apr 26 12:56:12.279202 P@ck-Control[773]:  [948 rds_plc]; [INFO] PLC-Thread with id 948 runs on cpu 0 only!
  439. 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!
  440. 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!
  441. Apr 26 12:56:12.492269 start-rds.sh[664]: === /opt/rovema/system/timezone.sh -- start writing /var/log/timezonelist.txt ===
  442. Apr 26 12:56:12.525151 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase0  finished!!
  443. Apr 26 12:56:12.525621 P@ck-Control[773]:  [954 SercosService]; [INFO] SD3_CreateAddressTable for 2 slaves
  444. 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!
  445. Apr 26 12:56:12.526166 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '0' with _switchDown = 0; m_bAutoConfiguration = 0!
  446. Apr 26 12:56:12.526568 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '0' (1) with bReady = 1!
  447. Apr 26 12:56:12.526820 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '0' with bReady = 1!
  448. Apr 26 12:56:12.527280 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase1...
  449. Apr 26 12:56:12.540966 P@ck-Control[773]:  [954 SercosService]; [INFO]  m_CSMD_Instance.rPriv.pusTxRam is now :0x985c17c!
  450. 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!
  451. Apr 26 12:56:12.693073 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase1  finished!!
  452. Apr 26 12:56:12.693499 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '1' with _switchDown = 0; m_bAutoConfiguration = 0!
  453. Apr 26 12:56:12.693789 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '1' (1) with bReady = 1!
  454. Apr 26 12:56:12.694073 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '1' with bReady = 1!
  455. Apr 26 12:56:12.694970 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase2...
  456. Apr 26 12:56:12.834985 P@ck-Control[773]:  [954 SercosService]; [INFO] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase2  finished!!
  457. Apr 26 12:56:12.837015 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCheckVersion...
  458. Apr 26 12:56:12.861291 REUS[786]:  [786 ReusMain]; [INFO] Initializing updater module...
  459. Apr 26 12:56:12.865836 REUS[786]:  [786 ReusMain]; [ERROR] No device available or device has been unplugged.
  460. Apr 26 12:56:12.866297 REUS[786]:  [786 ReusMain]; [ERROR] Update failed: No device available or device has been unplugged.
  461. Apr 26 12:56:12.866746 REUS[786]:  [786 ReusMain]; [ERROR] No device available or device has been unplugged.
  462. Apr 26 12:56:12.867624 REUS[786]:  [786 ReusMain]; [INFO] Updater initialization done
  463. Apr 26 12:56:12.876719 REUS[786]:  [786 ReusMain]; [INFO] SnapShot initialized...
  464. Apr 26 12:56:12.877053 REUS[786]:  [786 ReusMain]; [INFO] Watchdog initialized
  465. 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.
  466. Apr 26 12:56:12.877553 REUS[786]:  [786 ReusMain]; [NOTICE] CopyToUSB initialized.
  467. 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.
  468. Apr 26 12:56:12.878022 REUS[786]:  [786 ReusMain]; [NOTICE] REUS initialized successfully
  469. 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.
  470. 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.
  471. 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
  472. 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
  473. 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
  474. Apr 26 12:56:13.005009 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCheckVersion finished!
  475. Apr 26 12:56:13.005457 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '2' with _switchDown = 0; m_bAutoConfiguration = 0!
  476. Apr 26 12:56:13.005756 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase startUp!
  477. Apr 26 12:56:13.006464 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '2' (0) with bReady = 1!
  478. Apr 26 12:56:13.006756 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '2' (1) with bReady = 1!
  479. Apr 26 12:56:13.007082 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!!
  480. Apr 26 12:56:13.007352 P@ck-Control[773]:  [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1.
  481. Apr 26 12:56:13.007600 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '2' with bReady = 1!
  482. Apr 26 12:56:13.007855 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp();wait for m_ReadyForStartup= 1 ! Ok 1
  483. Apr 26 12:56:13.026624 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Sercos3Driver::startUp(); setUpNRTchannel from startUp!
  484. Apr 26 12:56:13.027071 P@ck-Control[773]: setUCCAddr HW-ADDR:  06-f5-27-e5-a8-90;
  485. Apr 26 12:56:13.027376 P@ck-Control[773]: setUCCAddr setup ifr done
  486. Apr 26 12:56:13.071459 P@ck-Control[773]:  [952 SercosInit]; [DEBUG] C_Sercos3Driver::_initThread set m_setCommunicationPhase to 4 again!
  487. 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;
  488. 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;
  489. Apr 26 12:56:14.310000 ntpd[592]: Listen normally on 10 serc0 192.168.108.254 UDP 123
  490. Apr 26 12:56:14.310404 ntpd[592]: Listen normally on 11 eth2 fe80::d6f5:27ff:fee5:a890 UDP 123
  491. Apr 26 12:56:14.310674 ntpd[592]: Listen normally on 12 serc0 fe80::4f5:27ff:fee5:a890 UDP 123
  492. Apr 26 12:56:14.311076 ntpd[592]: peers refreshed
  493. Apr 26 12:56:14.784997 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_Sercos3Driver::finishTransferOfPhase(); setUpNRTchannel from finishTransferOfPhase!
  494. Apr 26 12:56:14.785414 P@ck-Control[773]: setUCCAddr HW-ADDR:  06-f5-27-e5-a8-90;
  495. Apr 26 12:56:14.785667 P@ck-Control[773]: setUCCAddr setup ifr done
  496. Apr 26 12:56:14.787362 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetGetTimingData ...
  497. Apr 26 12:56:14.877837 startadapter.sh[665]: WebSocketConnection::send() called without connection
  498. Apr 26 12:56:14.878739 startadapter.sh[665]: WebSocketConnection::send() called without connection
  499. Apr 26 12:56:14.879207 startadapter.sh[665]: WebSocketConnection::send() called without connection
  500. Apr 26 12:56:14.879588 startadapter.sh[665]: WebSocketConnection::send() called without connection
  501. 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
  502. 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
  503. Apr 26 12:56:15.151699 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetGetTimingData finished!
  504. Apr 26 12:56:15.154383 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetCalculateTiming finished!
  505. Apr 26 12:56:15.154935 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetTransmitTiming ...
  506. Apr 26 12:55:48.322690 systemd[1]: Time has been changed
  507. 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  /
  508. Apr 26 12:55:48.350275 startadapter.sh[665]: WebSocket connection ws://127.0.0.1:9002 established
  509. 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
  510. 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
  511. Apr 26 12:55:48.836819 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetTransmitTiming finished!
  512. Apr 26 12:55:48.838759 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase3 ...
  513. Apr 26 12:55:49.578853 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase3 finished Set 4, SwitchPhase: 1!
  514. Apr 26 12:55:49.579337 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '3' with _switchDown = 0; m_bAutoConfiguration = 0!
  515. Apr 26 12:55:49.579704 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '3' (1) with bReady = 1!
  516. Apr 26 12:55:49.579998 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosIoLinkModule '03-A101:1':  Type 0x10201; State: 10
  517. Apr 26 12:55:49.580315 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!!
  518. Apr 26 12:55:49.580598 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosIoLinkModule '03-A101:1':  Type 0x10201; State: 10
  519. Apr 26 12:55:49.580926 P@ck-Control[773]:  [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1.
  520. Apr 26 12:55:49.581213 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '3' with bReady = 1!
  521. Apr 26 12:55:49.581498 P@ck-Control[773]:  [954 SercosService]; [DEBUG] NIC-timed transmission times: MDT: 100ns; AT: 41060ns; UCC: 399530ns
  522. Apr 26 12:55:49.682820 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase4 ...
  523. Apr 26 12:55:49.747806 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_SercosDriverBase::sercosPhaseHandling: eSercosPhaseSetPhase4 finished Set 4, SwitchPhase: 0!
  524. Apr 26 12:55:49.748153 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '4' with _switchDown = 0; m_bAutoConfiguration = 0!
  525. Apr 26 12:55:49.748437 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '4' (1) with bReady = 1!
  526. Apr 26 12:55:49.748726 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase startServiceChannels!!
  527. Apr 26 12:55:49.748987 P@ck-Control[773]:  [954 SercosService]; [DEBUG] Sercos3Driver: Start ServiceChannels 1.
  528. 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
  529. Apr 26 12:55:49.749486 P@ck-Control[773]:  [954 SercosService]; [DEBUG] Sercos3IOTest::startup OK 1 '0x99' = IO_Modul_102
  530. Apr 26 12:55:49.749783 P@ck-Control[773]:  [954 SercosService]; [DEBUG] Sercos3IOTest::startup OK 1 '0x41fe' = IO_Modul_163
  531. Apr 26 12:55:49.750029 P@ck-Control[773]:  [954 SercosService]; [DEBUG] transferDataOfPhase '4' with bReady = 1!
  532. Apr 26 12:55:49.750292 P@ck-Control[773]:  [954 SercosService]; [DEBUG] NIC-timed transmission times: MDT: 100ns; AT: 41060ns; UCC: 399530ns
  533. 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!
  534. Apr 26 12:55:49.772352 P@ck-Control[773]:  [952 SercosInit]; [INFO] SD3_CreateAddressTable for 2 slaves
  535. 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!
  536. 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')
  537. Apr 26 12:55:49.791639 P@ck-Control[773]:  [773 init_rds]; [INFO] Sercos Startup done
  538. Apr 26 12:55:49.792075 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RdsExecutableList::startUp 0 started
  539. Apr 26 12:55:49.892757 P@ck-Control[773]:  [948 rds_plc]; [NOTICE] C_Dio_PowerFail: no node 'Boards.vxhost.Hardware.PowerDetection' found!
  540. Apr 26 12:55:49.893213 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Finish RDC-Client!! : RdsExecutable
  541. Apr 26 12:55:49.893511 P@ck-Control[773]:  [948 rds_plc]; [NOTICE] C_Dio_PowerFail: will be deleted!
  542. 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
  543. Apr 26 12:55:49.912886 P@ck-Control[773]:  [853 infoRdsExecutable]; [INFO] Finish Informtask of RdsExecutable -1- HostCPU(127.0.0.1):
  544. Apr 26 12:55:49.992836 P@ck-Control[773]:  [948 rds_plc]; [NOTICE] Plc startup :
  545. Apr 26 12:55:49.993270 P@ck-Control[773]:  [948 rds_plc]; [NOTICE] Plc startup executed
  546. Apr 26 12:55:50.012948 P@ck-Control[773]:  [773 init_rds]; [DEBUG] Delete C_RdsClientLocal of 'RdsExecutable'
  547. Apr 26 12:55:50.209950 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !!
  548. Apr 26 12:55:50.355779 P@ck-Control[773]:  [781 rdsRmaServ]; [DEBUG] _RdsDataStream::_saveFullNodeStructure of 'System.RDS.LatestDataTree' ready
  549. Apr 26 12:55:51.625825 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !!
  550. Apr 26 12:55:51.771964 P@ck-Control[773]:  [952 SercosInit]; [DEBUG] C_Sercos3Driver::initThread(): finished normal!
  551. Apr 26 12:55:53.041821 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !!
  552. 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
  553. Apr 26 12:55:54.020713 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no exclude list configured
  554. Apr 26 12:55:54.021173 P@ck-Control[773]:  [773 init_rds]; [DEBUG] C_Archive::tar_create: no exclude list configured
  555. Apr 26 12:55:54.181274 P@ck-Control[773]:  [773 init_rds]; [INFO] C_RovArchive::tar_create: no include list configured
  556. Apr 26 12:55:54.200150 P@ck-Control[773]:  [773 init_rds]; [DEBUG] StartMainLoop
  557. Apr 26 12:55:54.460826 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_Sercos3ServiceChannel::readIOContainer() !!
  558. Apr 26 12:55:55.651966 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'DeviceAccessLocks' success 1!!
  559. Apr 26 12:56:02.280985 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Hysteresis' success 1 value 82!!
  560. Apr 26 12:56:02.281393 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointMode' success 1 value 1!!
  561. Apr 26 12:56:02.281715 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointLogic' success 1 value 0!!
  562. Apr 26 12:56:02.281996 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutA_SwitchParameters' success 1!!
  563. Apr 26 12:56:02.823936 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SetPointHigh' success 1 value 11468!!
  564. Apr 26 12:56:02.824325 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SetPointLow' success 1 value 9830!!
  565. Apr 26 12:56:02.824579 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutB_SetPoints' success 1!!
  566. Apr 26 12:56:03.445879 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Hysteresis' success 1 value 82!!
  567. Apr 26 12:56:03.446284 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointMode' success 1 value 1!!
  568. Apr 26 12:56:03.446543 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'SwitchPointLogic' success 1 value 0!!
  569. Apr 26 12:56:03.446848 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'OutB_SwitchParameters' success 1!!
  570. Apr 26 12:56:08.368881 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Setpoint_SP2' success 1 value 0!!
  571. Apr 26 12:56:08.369279 P@ck-Control[773]:  [954 SercosService]; [DEBUG]  C_IoLinkSignedIntValue::writeToIoLink 'Setpoint_SP1_in___between_mark_and_background'
  572. 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 !!
  573. 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!!
  574. Apr 26 12:56:08.370103 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'Qint_1_SP1_SP2' success 1!!
  575. Apr 26 12:56:08.850883 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Switchpoint_hysteresis' success 1 value 0!!
  576. 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!!
  577. Apr 26 12:56:08.851537 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkUnsignedIntValue::writeToIoLink() of 'Switchpoint_logic___Inversion' success 1 value 128!!
  578. Apr 26 12:56:08.851847 P@ck-Control[773]:  [954 SercosService]; [DEBUG] C_IoLinkRecord::startWrite() of 'Qint_1_configuration' success 1!!
  579. Apr 26 12:59:41.478748 synchwclock.sh[667]: unsynchronised
  580. Apr 26 12:59:41.479222 synchwclock.sh[667]: polling server every 64 s
  581. Apr 26 13:03:41.481645 synchwclock.sh[667]: unsynchronised
  582. Apr 26 13:03:41.482239 synchwclock.sh[667]: polling server every 64 s
  583. Apr 26 13:05:01.214989 CRON[1218]: pam_unix(cron:session): session opened for user root by (uid=0)
  584. Apr 26 13:05:01.215818 CRON[1219]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  585. Apr 26 13:05:01.218610 CRON[1218]: pam_unix(cron:session): session closed for user root
  586. Apr 26 13:07:41.484445 synchwclock.sh[667]: unsynchronised
  587. Apr 26 13:07:41.484775 synchwclock.sh[667]: polling server every 64 s
  588. Apr 26 13:10:34.678487 systemd[1]: Starting Cleanup of Temporary Directories...
  589. Apr 26 13:10:34.687069 systemd[1]: Started Cleanup of Temporary Directories.
  590. Apr 26 13:11:41.487648 synchwclock.sh[667]: synchronised to NTP server (124.216.164.14) at stratum 2
  591. Apr 26 13:11:41.487970 synchwclock.sh[667]: time correct to within 18 ms
  592. Apr 26 13:11:41.488494 synchwclock.sh[667]: polling server every 64 s
  593. 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
  594. Apr 26 13:11:42.501394 synchwclock.sh[667]: hwclock: Drift adjustment parameters not updated.
  595. Apr 26 13:15:01.222191 CRON[1241]: pam_unix(cron:session): session opened for user root by (uid=0)
  596. Apr 26 13:15:01.222702 CRON[1242]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  597. Apr 26 13:15:01.225064 CRON[1241]: pam_unix(cron:session): session closed for user root
  598. Apr 26 13:17:01.227671 CRON[1244]: pam_unix(cron:session): session opened for user root by (uid=0)
  599. Apr 26 13:17:01.228445 CRON[1245]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
  600. Apr 26 13:17:01.231075 CRON[1244]: pam_unix(cron:session): session closed for user root
  601. Apr 26 13:25:01.234174 CRON[1255]: pam_unix(cron:session): session opened for user root by (uid=0)
  602. Apr 26 13:25:01.235090 CRON[1256]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  603. Apr 26 13:25:01.237328 CRON[1255]: pam_unix(cron:session): session closed for user root
  604. Apr 26 13:35:01.240779 CRON[1268]: pam_unix(cron:session): session opened for user root by (uid=0)
  605. Apr 26 13:35:01.243430 CRON[1269]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  606. Apr 26 13:35:01.243534 CRON[1268]: pam_unix(cron:session): session closed for user root
  607. Apr 26 13:45:01.247099 CRON[1281]: pam_unix(cron:session): session opened for user root by (uid=0)
  608. Apr 26 13:45:01.248151 CRON[1282]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  609. Apr 26 13:45:01.249860 CRON[1281]: pam_unix(cron:session): session closed for user root
  610. Apr 26 13:48:33.662657 sshd[1286]: Accepted password for root from 192.168.19.107 port 51244 ssh2
  611. Apr 26 13:48:33.665653 sshd[1286]: pam_unix(sshd:session): session opened for user root by (uid=0)
  612. Apr 26 13:49:30.126046 sshd[1294]: Accepted password for root from 192.168.19.107 port 51311 ssh2
  613. Apr 26 13:49:30.128760 sshd[1294]: pam_unix(sshd:session): session opened for user root by (uid=0)
  614. Apr 26 13:55:01.253407 CRON[1308]: pam_unix(cron:session): session opened for user root by (uid=0)
  615. Apr 26 13:55:01.256065 CRON[1309]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  616. Apr 26 13:55:01.256255 CRON[1308]: pam_unix(cron:session): session closed for user root
  617. Apr 26 14:05:01.259718 CRON[1321]: pam_unix(cron:session): session opened for user root by (uid=0)
  618. Apr 26 14:05:01.260265 CRON[1322]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  619. Apr 26 14:05:01.262370 CRON[1321]: pam_unix(cron:session): session closed for user root
  620. Apr 26 14:15:01.266009 CRON[1336]: pam_unix(cron:session): session opened for user root by (uid=0)
  621. Apr 26 14:15:01.268922 CRON[1337]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  622. Apr 26 14:15:01.269037 CRON[1336]: pam_unix(cron:session): session closed for user root
  623. Apr 26 14:17:01.271454 CRON[1341]: pam_unix(cron:session): session opened for user root by (uid=0)
  624. Apr 26 14:17:01.271998 CRON[1342]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
  625. Apr 26 14:17:01.274116 CRON[1341]: pam_unix(cron:session): session closed for user root
  626. Apr 26 14:25:01.277282 CRON[1350]: pam_unix(cron:session): session opened for user root by (uid=0)
  627. Apr 26 14:25:01.278235 CRON[1351]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  628. Apr 26 14:25:01.280112 CRON[1350]: pam_unix(cron:session): session closed for user root
  629. Apr 26 14:35:01.283641 CRON[1363]: pam_unix(cron:session): session opened for user root by (uid=0)
  630. Apr 26 14:35:01.284218 CRON[1364]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  631. Apr 26 14:35:01.286422 CRON[1363]: pam_unix(cron:session): session closed for user root

Submit a correction or amendment below (click here to make a fresh posting)
After submitting an amendment, you'll be able to view the differences between the old and new posts easily.

Syntax highlighting:

To highlight particular lines, prefix each line with {%HIGHLIGHT}




All content is user-submitted.
The administrators of this site (kpaste.net) are not responsible for their content.
Abuse reports should be emailed to us at