pastebin - collaborative debugging tool
rovema.kpaste.net RSS


Syslog_220801_PThread_Prio_Mekker
Posted by Anonymous on Mon 1st Aug 2022 12:43
raw | new post
view followups (newest first): RDS syslog: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced, with backtraces by Anonymous

  1. Aug  1 13:34:48.792987 rsyslogd: [origin software="rsyslogd" swVersion="8.4.2" x-pid="565" x-info="http://www.rsyslog.com"] start
  2. Aug  1 13:34:48.793345 systemd[1]: Started Apply Kernel Variables.
  3. Aug  1 13:34:48.793386 systemd[1]: Started Create Static Device Nodes in /dev.
  4. Aug  1 13:34:48.793408 systemd[1]: Starting udev Kernel Device Manager...
  5. Aug  1 13:34:48.793426 systemd[1]: Started udev Kernel Device Manager.
  6. Aug  1 13:34:48.793442 systemd[1]: Starting Copy rules generated while the root was ro...
  7. Aug  1 13:34:48.793461 systemd[1]: Starting LSB: Set preliminary keymap...
  8. Aug  1 13:34:48.793478 systemd[1]: Started Copy rules generated while the root was ro.
  9. Aug  1 13:34:48.793496 systemd[1]: Started LSB: Set preliminary keymap.
  10. Aug  1 13:34:48.793515 systemd[1]: Starting Show Plymouth Boot Screen...
  11. Aug  1 13:34:48.793533 systemd[1]: Starting Remount Root and Kernel File Systems...
  12. Aug  1 13:34:48.793551 systemd[1]: Started Remount Root and Kernel File Systems.
  13. Aug  1 13:34:48.793569 systemd[1]: Starting Create Volatile Files and Directories...
  14. Aug  1 13:34:48.793588 systemd[1]: Starting Remote File Systems.
  15. Aug  1 13:34:48.793606 systemd[1]: Reached target Remote File Systems.
  16. Aug  1 13:34:48.793622 systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
  17. Aug  1 13:34:48.793640 systemd[1]: Starting LSB: Set console font and keymap...
  18. Aug  1 13:34:48.793658 systemd[1]: Starting LSB: Raise network interfaces....
  19. Aug  1 13:34:48.793676 systemd[1]: Started Create Volatile Files and Directories.
  20. Aug  1 13:34:48.793693 systemd[1]: Started LSB: Set console font and keymap.
  21. Aug  1 13:34:48.793711 systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
  22. Aug  1 13:34:48.793728 systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
  23. Aug  1 13:34:48.793745 systemd[1]: Starting Update UTMP about System Boot/Shutdown...
  24. Aug  1 13:34:48.793763 systemd[1]: Started Update UTMP about System Boot/Shutdown.
  25. Aug  1 13:34:48.793781 networking[355]: Configuring network interfaces...start exec /usr/bin/setDnsHostname for eth0 from /etc/network/if-up.d/setHostname
  26. Aug  1 13:34:48.793799 networking[355]: done.
  27. Aug  1 13:34:48.793818 systemd[1]: Started LSB: Raise network interfaces..
  28. Aug  1 13:34:48.793836 systemd[1]: Starting Network.
  29. Aug  1 13:34:48.793855 systemd[1]: Reached target Network.
  30. Aug  1 13:34:48.793873 systemd[1]: Starting Network is Online.
  31. Aug  1 13:34:48.793890 systemd[1]: Reached target Network is Online.
  32. Aug  1 13:34:48.793908 systemd[1]: Starting System Initialization.
  33. Aug  1 13:34:48.793926 systemd[1]: Reached target System Initialization.
  34. Aug  1 13:34:48.793944 systemd[1]: Starting Daily Cleanup of Temporary Directories.
  35. Aug  1 13:34:48.793962 systemd[1]: Started Daily Cleanup of Temporary Directories.
  36. Aug  1 13:34:48.793979 systemd[1]: Starting Timers.
  37. Aug  1 13:34:48.793997 systemd[1]: Reached target Timers.
  38. Aug  1 13:34:48.794014 systemd[1]: Starting Basic System.
  39. Aug  1 13:34:48.794031 systemd[1]: Reached target Basic System.
  40. Aug  1 13:34:48.794050 systemd[1]: Starting OpenBSD Secure Shell server...
  41. Aug  1 13:34:48.794068 systemd[1]: Started OpenBSD Secure Shell server.
  42. Aug  1 13:34:48.794085 systemd[1]: Starting Lighttpd Daemon...
  43. Aug  1 13:34:48.794103 systemd[1]: Starting Pure-FTPd FTP server...
  44. Aug  1 13:34:48.794121 systemd[1]: Starting Regular background program processing daemon...
  45. Aug  1 13:34:48.794139 systemd[1]: Started Regular background program processing daemon.
  46. Aug  1 13:34:48.794157 systemd[1]: Starting Installation-script for P@ckControl...
  47. Aug  1 13:34:48.794174 systemd[1]: Starting Internet superserver...
  48. Aug  1 13:34:48.794192 cron[551]: (CRON) INFO (pidfile fd = 3)
  49. Aug  1 13:34:48.794209 systemd[1]: Started Internet superserver.
  50. Aug  1 13:34:48.794227 systemd[1]: Starting Initialize hardware monitoring sensors...
  51. Aug  1 13:34:48.794245 systemd[1]: Starting Pure-FTPd FTP server...
  52. Aug  1 13:34:48.794262 systemd[1]: Starting /etc/rc.local Compatibility...
  53. Aug  1 13:34:48.794280 cron[551]: (CRON) INFO (Running @reboot jobs)
  54. Aug  1 13:34:48.796492 systemd[1]: Starting getty on tty2-tty6 if dbus and logind are not available...
  55. Aug  1 13:34:48.796507 systemd[1]: Started Login Service.
  56. Aug  1 13:34:48.796513 systemd[1]: Starting LSB: No Display Manager...
  57. Aug  1 13:34:48.796517 systemd[1]: Starting LSB: Start NTP daemon...
  58. Aug  1 13:34:48.796521 systemd[1]: Starting LSB: Start/stop sysstat's sadc...
  59. Aug  1 13:34:48.796526 systemd[1]: Starting System Logging Service...
  60. Aug  1 13:34:48.796530 systemd[1]: Starting Permit User Sessions...
  61. Aug  1 13:34:48.796536 systemd[1]: Started Installation-script for P@ckControl.
  62. Aug  1 13:34:48.796540 systemd[1]: Started /etc/rc.local Compatibility.
  63. Aug  1 13:34:48.796544 systemd[1]: PID file /var/run/pure-uploadscript.pid not readable (yet?) after start.
  64. Aug  1 13:34:48.796549 systemd[1]: Started LSB: Start/stop sysstat's sadc.
  65. Aug  1 13:34:48.796552 systemd[1]: Started Permit User Sessions.
  66. Aug  1 13:34:48.796556 systemd[1]: Received SIGRTMIN+21 from PID 151 (plymouthd).
  67. Aug  1 13:34:48.796560 sensors[582]: acpitz-virtual-0
  68. Aug  1 13:34:48.796565 sensors[582]: Adapter: Virtual device
  69. Aug  1 13:34:48.796570 sensors[582]: temp1:        +47.0 C  (crit = +105.0 C)
  70. Aug  1 13:34:48.796574 sensors[582]: temp2:        +47.0 C  (crit = +105.0 C)
  71. Aug  1 13:34:48.796578 sensors[582]: coretemp-isa-0000
  72. Aug  1 13:34:48.796582 sensors[582]: Adapter: ISA adapter
  73. Aug  1 13:34:48.796586 sensors[582]: Physical id 0:  +45.0 C  (high = +100.0 C, crit = +100.0 C)
  74. Aug  1 13:34:48.796590 sensors[582]: Core 0:         +44.0 C  (high = +100.0 C, crit = +100.0 C)
  75. Aug  1 13:34:48.796594 sensors[582]: Core 1:         +45.0 C  (high = +100.0 C, crit = +100.0 C)
  76. Aug  1 13:34:48.796598 systemd[1]: Started Initialize hardware monitoring sensors.
  77. Aug  1 13:34:48.796605 ntp[559]: Starting NTP server: ntpderror: unexpectedly disconnected from boot status daemon
  78. Aug  1 13:34:48.796609 systemd[1]: Starting Wait for Plymouth Boot Screen to Quit...
  79. Aug  1 13:34:48.796613 systemd[1]: Starting Terminate Plymouth Boot Screen...
  80. Aug  1 13:34:48.796617 systemd[1]: Starting pcupdate for P@ckControl...
  81. Aug  1 13:34:48.796621 systemd[1]: Started LSB: No Display Manager.
  82. Aug  1 13:34:48.796625 systemd[1]: Started Wait for Plymouth Boot Screen to Quit.
  83. Aug  1 13:34:48.796629 systemd[1]: Starting Getty on tty6...
  84. Aug  1 13:34:48.796633 systemd[1]: Started Getty on tty6.
  85. Aug  1 13:34:48.796637 systemd[1]: Starting Getty on tty5...
  86. Aug  1 13:34:48.796643 systemd[1]: Started Getty on tty5.
  87. Aug  1 13:34:48.796647 systemd[1]: Starting Getty on tty4...
  88. Aug  1 13:34:48.796651 systemd[1]: Started Getty on tty4.
  89. Aug  1 13:34:48.796655 systemd[1]: Starting Getty on tty3...
  90. Aug  1 13:34:48.796659 systemd[1]: Started Getty on tty3.
  91. Aug  1 13:34:48.796663 systemd[1]: Starting Getty on tty2...
  92. Aug  1 13:34:48.796667 systemd[1]: Started Getty on tty2.
  93. Aug  1 13:34:48.796671 systemd[1]: Starting Getty on tty1...
  94. Aug  1 13:34:48.793519 systemd[1]: Started Getty on tty1.
  95. Aug  1 13:34:48.793813 fortytwo.sh[593]: mkdir: cannot create directory '/var/log/lighttpd': File exists
  96. Aug  1 13:34:48.794096 fortytwo.sh[593]: last boot...succeeded
  97. Aug  1 13:34:48.794638 systemd[1]: Started System Logging Service.
  98. Aug  1 13:34:48.795407 nodm[590]: starting nodm
  99. Aug  1 13:34:48.795822 ntpd[587]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:48:00 UTC 2016 (1)
  100. Aug  1 13:34:48.795924 ntpd[607]: proto: precision = 0.412 usec
  101. Aug  1 13:34:48.797402 ntpd[607]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
  102. Aug  1 13:34:48.797698 ntp[559]: .
  103. Aug  1 13:34:48.798350 systemd[1]: Started LSB: Start NTP daemon.
  104. Aug  1 13:34:48.799198 systemd[1]: Started Terminate Plymouth Boot Screen.
  105. Aug  1 13:34:48.802539 systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
  106. Aug  1 13:34:48.804312 ntpd[607]: Listen and drop on 1 v6wildcard :: UDP 123
  107. Aug  1 13:34:48.804604 ntpd[607]: Listen normally on 2 lo 127.0.0.1 UDP 123
  108. Aug  1 13:34:48.804855 ntpd[607]: Listen normally on 3 eth1 169.254.254.254 UDP 123
  109. Aug  1 13:34:48.805127 systemd[1]: PID file /var/run/pure-ftpd.pid not readable (yet?) after start.
  110. Aug  1 13:34:48.806244 ntpd[607]: Listen normally on 4 eth1:1 192.168.1.193 UDP 123
  111. Aug  1 13:34:48.806584 ntpd[607]: Listen normally on 5 eth3 169.254.253.238 UDP 123
  112. Aug  1 13:34:48.806827 ntpd[607]: Listen normally on 6 eth2 169.254.253.254 UDP 123
  113. Aug  1 13:34:48.807057 ntpd[607]: Listen normally on 7 eth0 10.49.28.56 UDP 123
  114. Aug  1 13:34:48.807318 ntpd[607]: Listen normally on 8 lo ::1 UDP 123
  115. Aug  1 13:34:48.807558 ntpd[607]: peers refreshed
  116. Aug  1 13:34:48.807808 ntpd[607]: Listening on routing socket on fd #25 for interface updates
  117. Aug  1 13:34:48.808082 fortytwo.sh[593]: No recalibration is necessary!
  118. Aug  1 13:34:48.808431 fortytwo.sh[593]: Calibration file remains untouched.
  119. Aug  1 13:34:48.811333 ntpd[607]: Deferring DNS for ptbtime1.ptb.de 1
  120. Aug  1 13:34:48.811640 sshd[548]: Server listening on 0.0.0.0 port 22.
  121. Aug  1 13:34:48.811930 sshd[548]: Server listening on :: port 22.
  122. Aug  1 13:34:48.812192 ntpd[607]: Deferring DNS for ptbtime2.ptb.de 1
  123. Aug  1 13:34:48.812764 ntpd[607]: Deferring DNS for ptbtime3.ptb.de 1
  124. Aug  1 13:34:48.813044 fortytwo.sh[593]: 17637_CMH-C_EPU_Abbott.RDF filesize seems to be ok - file remains untouched
  125. Aug  1 13:34:48.813620 systemd[1]: Starting Login Prompts.
  126. Aug  1 13:34:48.813813 systemd[1]: Reached target Login Prompts.
  127. Aug  1 13:34:48.821782 fortytwo.sh[593]: check for prepared updates...no updates available
  128. Aug  1 13:34:48.823301 fortytwo.sh[593]: Check: /opt/update/rovema/javis
  129. Aug  1 13:34:48.824680 fortytwo.sh[593]: no files to update available in /opt/update/rovema/javis
  130. Aug  1 13:34:48.824984 fortytwo.sh[593]: Check: /opt/update/rovema/calcmod
  131. Aug  1 13:34:48.826743 fortytwo.sh[593]: no files to update available in /opt/update/rovema/calcmod
  132. Aug  1 13:34:48.827021 fortytwo.sh[593]: Check: /opt/update/rovema/adapter
  133. Aug  1 13:34:48.829493 fortytwo.sh[593]: no files to update available in /opt/update/rovema/adapter
  134. Aug  1 13:34:48.829725 fortytwo.sh[593]: Check: /opt/update/rovema/rds
  135. Aug  1 13:34:48.830005 fortytwo.sh[593]: no files to update available in /opt/update/rovema/rds
  136. Aug  1 13:34:48.830280 fortytwo.sh[593]: Check: /opt/update/rovema/plc
  137. Aug  1 13:34:48.831552 fortytwo.sh[593]: no files to update available in /opt/update/rovema/plc
  138. Aug  1 13:34:48.832248 fortytwo.sh[593]: Check: /opt/update/rovema/lib
  139. Aug  1 13:34:48.833631 fortytwo.sh[593]: no files to update available in /opt/update/rovema/lib
  140. Aug  1 13:34:48.834323 fortytwo.sh[593]: cleaning update folder...done
  141. Aug  1 13:34:48.835237 fortytwo.sh[593]: restoring update file structure...done
  142. Aug  1 13:34:48.841591 lighttpd[549]: Syntax OK
  143. Aug  1 13:34:48.846674 systemd[1]: Started Lighttpd Daemon.
  144. Aug  1 13:34:49.037466 nodm[664]: Successful su on ??? for root by root
  145. Aug  1 13:34:49.038520 nodm[664]: pam_unix(nodm:session): session opened for user root by (uid=0)
  146. Aug  1 13:34:49.559411 fortytwo.sh[593]: chown: changing ownership of '/opt/backup/lost+found': Read-only file system
  147. Aug  1 13:34:49.559730 fortytwo.sh[593]: chown: changing ownership of '/opt/backup/packcontrol.tar.gz': Read-only file system
  148. Aug  1 13:34:49.559988 fortytwo.sh[593]: chown: changing ownership of '/opt/backup': Read-only file system
  149. Aug  1 13:34:49.657646 fortytwo.sh[593]: chmod: changing permissions of '/opt/backup': Read-only file system
  150. Aug  1 13:34:49.657980 fortytwo.sh[593]: chmod: changing permissions of '/opt/backup/lost+found': Read-only file system
  151. Aug  1 13:34:49.658374 fortytwo.sh[593]: chmod: changing permissions of '/opt/backup/packcontrol.tar.gz': Read-only file system
  152. Aug  1 13:34:49.660499 fortytwo.sh[593]: chmod: cannot access '/opt/rovema/javis/startJavis.sh': No such file or directory
  153. Aug  1 13:34:49.661797 fortytwo.sh[593]: done(ext3)
  154. Aug  1 13:34:49.662592 systemd[1]: Started pcupdate for P@ckControl.
  155. Aug  1 13:34:49.662866 systemd[1]: Starting Start the RDS, REUS and other deamons of P@ckControl...
  156. Aug  1 13:34:49.667488 systemd[1]: Starting Adapter (HMI-Adapter)...
  157. Aug  1 13:34:49.668228 systemd[1]: Started Adapter (HMI-Adapter).
  158. Aug  1 13:34:49.668669 systemd[1]: Starting Synchronize HwClock with system clock...
  159. Aug  1 13:34:49.670044 systemd[1]: Started Synchronize HwClock with system clock.
  160. Aug  1 13:34:49.675750 synchwclock.sh[733]: unsynchronised
  161. Aug  1 13:34:49.676568 synchwclock.sh[733]: time server re-starting
  162. Aug  1 13:34:49.676894 synchwclock.sh[733]: polling server every 8 s
  163. Aug  1 13:34:49.678033 synchwclock.sh[733]: rdate: ptbtime1.ptb.de: Name or service not known
  164. Aug  1 13:34:49.678607 startadapter.sh[732]: Set NAT-address of 192.168.1.194 to 192.168.56.194.
  165. Aug  1 13:34:49.701809 startadapter.sh[732]: Set NAT-address of 192.168.1.195 to 192.168.56.195.
  166. Aug  1 13:34:49.705806 startadapter.sh[732]: Set NAT-address of 192.168.1.196 to 192.168.56.196.
  167. Aug  1 13:34:49.709909 startadapter.sh[732]: Set NAT-address of 192.168.1.197 to 192.168.56.197.
  168. Aug  1 13:34:49.713543 startadapter.sh[732]: Set NAT-address of 192.168.1.198 to 192.168.56.198.
  169. Aug  1 13:34:49.717231 startadapter.sh[732]: Set NAT-address of 192.168.1.199 to 192.168.56.199.
  170. Aug  1 13:34:49.720376 startadapter.sh[732]: Set NAT-address of 192.168.1.200 to 192.168.56.200.
  171. Aug  1 13:34:49.723556 startadapter.sh[732]: Set NAT-address of 192.168.1.201 to 192.168.56.201.
  172. Aug  1 13:34:49.727530 startadapter.sh[732]: Set NAT-address of 192.168.1.202 to 192.168.56.202.
  173. Aug  1 13:34:49.730945 startadapter.sh[732]: Set NAT-address of 192.168.1.203 to 192.168.56.203.
  174. Aug  1 13:34:49.734700 startadapter.sh[732]: Set NAT-address of 192.168.1.204 to 192.168.56.204.
  175. Aug  1 13:34:49.738842 startadapter.sh[732]: Set NAT-address of 192.168.1.205 to 192.168.56.205.
  176. Aug  1 13:34:49.742820 startadapter.sh[732]: Set NAT-address of 192.168.1.206 to 192.168.56.206.
  177. Aug  1 13:34:49.746462 systemd[1]: Started Pure-FTPd FTP server.
  178. Aug  1 13:34:49.747737 systemd[1]: Started Pure-FTPd FTP server.
  179. Aug  1 13:34:49.822118 start-rds.sh[731]: pthread_interceptor.so: Once: In our own pthread_mutex_init()
  180. Aug  1 13:34:49.822399 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0xb3986d84, was protocol=0
  181. Aug  1 13:34:49.830455 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0xb51bd188, was protocol=0
  182. Aug  1 13:34:49.830833 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0xb51bd208, was protocol=0
  183. Aug  1 13:34:49.831149 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0xb51bd288, was protocol=0
  184. Aug  1 13:34:49.833505 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x1893e3a0, was protocol=0
  185. Aug  1 13:34:49.833698 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x1893e3c0, was protocol=0
  186. Aug  1 13:34:49.836674 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x1893e5b8, was protocol=0
  187. Aug  1 13:34:49.836857 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x1893e5d8, was protocol=0
  188. Aug  1 13:34:49.890439 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x183a9a44, was protocol=0
  189. Aug  1 13:34:49.892641 start-rds.sh[731]: pthread_interceptor.so: PTHREAD_PRIO_INHERIT enforced for mutex=0x183a86a4, was protocol=0
  190. Aug  1 13:34:49.898667 P@ck-Control[756]:  [756 RdsMain]; [NOTICE] ++++++Initializing Rovema Data Server++++++
  191. Aug  1 13:34:49.899456 P@ck-Control[842]:  [842 rds_daemonize]; [INFO] Deamon have found stacksize of 131072 Byte set it to 524288 Byte max 131072 KiB,
  192. Aug  1 13:34:49.899746 P@ck-Control[842]:  [842 rds_daemonize]; [INFO] New stacksize of 524288 Byte should 524288 Byte max. 524288 KiB,
  193. Aug  1 13:34:49.900155 P@ck-Control[842]:  [842 init_rds]; [INFO] Working in directory '/opt/rovema/rds'
  194. Aug  1 13:34:49.901049 P@ck-Control[842]:  [842 init_rds]; [INFO] Registering standard signal handler (all)
  195. Aug  1 13:34:49.901258 start-rds.sh[731]: 13:34:49.897  [756 RdsMain]; [INFO] Command have found stacksize of 131072 Byte set it to 524288 Byte max 131072 KiB,
  196. Aug  1 13:34:49.901903 start-rds.sh[731]: 13:34:49.898  [756 RdsMain]; [NOTICE] heartbeat: 1 - exec: 0 - daemon: 1 -log-level: 6 - use-sram: 4 - signal handler: 0 - location: /opt/rovema/rds - rdf-file: 17637_CMH-C_EPU_Abbott.RDF
  197. Aug  1 13:34:49.902061 start-rds.sh[731]: 13:34:49.897  [756 RdsMain]; [INFO] Command have found stacksize of 131072 Byte set it to 524288 Byte max 131072 KiB,
  198. Aug  1 13:34:49.902218 start-rds.sh[731]: 13:34:49.898  [756 RdsMain]; [NOTICE] heartbeat: 1 - exec: 0 - daemon: 1 -log-level: 6 - use-sram: 4 - signal handler: 0 - location: /opt/rovema/rds - rdf-file: 17637_CMH-C_EPU_Abbott.RDF
  199. Aug  1 13:34:49.915437 systemd[1]: Started Start the RDS, REUS and other deamons of P@ckControl.
  200. Aug  1 13:34:49.915774 systemd[1]: Starting Multi-User System.
  201. Aug  1 13:34:49.916058 systemd[1]: Reached target Multi-User System.
  202. Aug  1 13:34:49.920813 systemd[1]: Starting Start the Javis the Rovama HMI...
  203. Aug  1 13:34:49.921187 systemd[1]: Started Start the Javis the Rovama HMI.
  204. Aug  1 13:34:49.921596 systemd[1]: Starting Graphical Interface.
  205. Aug  1 13:34:49.922201 systemd[1]: Reached target Graphical Interface.
  206. Aug  1 13:34:49.922489 systemd[1]: Starting Starts the touchscreen recalibration if neccessary...
  207. Aug  1 13:34:49.922695 systemd[1]: Started Starts the touchscreen recalibration if neccessary.
  208. Aug  1 13:34:49.922910 systemd[1]: Starting Update UTMP about System Runlevel Changes...
  209. Aug  1 13:34:49.928260 systemd[1]: Stopping Start the Javis the Rovama HMI...
  210. Aug  1 13:34:49.929955 systemd[1]: Started Update UTMP about System Runlevel Changes.
  211. Aug  1 13:34:49.930348 systemd[1]: Stopped Start the Javis the Rovama HMI.
  212. Aug  1 13:34:49.931052 systemd[1]: Startup finished in 2.800s (kernel) + 4.552s (userspace) = 7.352s.
  213. Aug  1 13:34:49.949831 REUS[848]:  [848 ReusMain]; [INFO] Trying to connect to server..
  214. Aug  1 13:34:49.950706 start-rds.sh[731]: Start RDC REUS to 127.0.0.1
  215. Aug  1 13:34:50.378016 startadapter.sh[732]: > ro-adapter@2.1.2 start /opt/rovema/adapter
  216. Aug  1 13:34:50.378267 startadapter.sh[732]: > 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"
  217. Aug  1 13:34:50.539112 P@ck-Control[842]:  [842 init_rds]; [INFO] s_TraceMarkerFd is now 6 error 0 'Success'
  218. Aug  1 13:34:50.540617 P@ck-Control[842]:  [897 powerFailDetection]; [INFO] found Janich & Klass - PC-S3
  219. Aug  1 13:34:50.545013 P@ck-Control[842]:  [842 init_rds]; [INFO] RDS: 1. NvRam found at /root/nvram0, size = 262144
  220. Aug  1 13:34:50.546504 P@ck-Control[842]:  [842 init_rds]; [INFO] C_RdsSram at 0xaff4d008 old 0xaffd9008 initialized with init = '07.07.2022 15:16:09.424'/0x181D8CDB750; shutDown = '01.08.2022 13:34:29.112'/0x182592F9DF8 file '17637_CMH-C_EPU_Abbott.RDF'
  221. Aug  1 13:34:50.546915 P@ck-Control[842]:  [842 init_rds]; [NOTICE] Load RDF from file: '17637_CMH-C_EPU_Abbott.RDF'.
  222. Aug  1 13:34:50.811446 ntpd_intres[618]: host name not found: ptbtime1.ptb.de
  223. Aug  1 13:34:50.811782 ntpd_intres[618]: host name not found: ptbtime2.ptb.de
  224. Aug  1 13:34:50.812038 ntpd_intres[618]: host name not found: ptbtime3.ptb.de
  225. Aug  1 13:34:51.018207 P@ck-Control[842]:  [842 init_rds]; [INFO] NVRAM '/nvram0' is used, remanent data are unimpaired!
  226. Aug  1 13:34:51.902070 P@ck-Control[842]:  [842 init_rds]; [NOTICE] RDS: 0 ric-files found.
  227. Aug  1 13:34:52.235500 P@ck-Control[842]:  [842 init_rds]; [WARNING] RdsSram C_RdsSram::copyToFileMap finished!
  228. Aug  1 13:34:53.198590 networking[355]: server localhost
  229. Aug  1 13:34:53.198717 networking[355]: update delete CMH-C-17637-Abbott.machines.loc. A
  230. Aug  1 13:34:53.198809 networking[355]: update delete CMH-C-17637-Abbott.machines.loc. A
  231. Aug  1 13:34:53.198897 networking[355]: send
  232. Aug  1 13:34:53.198989 networking[355]: update add CMH-C-17637-Abbott.machines.loc. 300 A 10.49.28.56
  233. Aug  1 13:34:53.199076 networking[355]: send
  234. Aug  1 13:34:53.204685 logger: Rovema - set nameserver 10.49.0.222 in /run/resolvconf/resolv.conf!
  235. Aug  1 13:34:53.206429 logger: Rovema - DNS-name set to CMH-C-17637-Abbott of eth0
  236. Aug  1 13:34:53.451347 startadapter.sh[732]: WebSocketConnection::send() called without connection
  237. Aug  1 13:34:53.451871 startadapter.sh[732]: WebSocketConnection::send() called without connection
  238. Aug  1 13:34:53.452150 startadapter.sh[732]: WebSocketConnection::send() called without connection
  239. Aug  1 13:34:53.452461 startadapter.sh[732]: WebSocketConnection::send() called without connection
  240. Aug  1 13:34:53.795397 ntpd[607]: Listen normally on 9 eth0 fe80::240:f2ff:fe36:574 UDP 123
  241. Aug  1 13:34:53.795816 ntpd[607]: peers refreshed
  242. Aug  1 13:34:54.152852 startadapter.sh[732]: WebSocket connection ws://127.0.0.1:9002 failed, trying to reconnect in 0.1 s
  243. Aug  1 13:34:54.273165 startadapter.sh[732]: WebSocket connection ws://127.0.0.1:9002 failed, trying to reconnect in 1.1 s
  244. Aug  1 13:34:57.661690 startadapter.sh[732]: WebSocket connection ws://127.0.0.1:9002 failed, trying to reconnect in 1.2 s
  245. Aug  1 13:34:57.744618 startadapter.sh[732]: WebSocketConnection::send() called without connection
  246. Aug  1 13:34:57.745123 startadapter.sh[732]: (node:889) Warning: N-API is an experimental feature and could change at any time.
  247. Aug  1 13:34:58.525200 P@ck-Control[842]:  [920 OpcUaThread]; [INFO] OPC-UA-thread start thread !
  248. Aug  1 13:34:58.525876 P@ck-Control[842]:  [842 init_rds]; [NOTICE] RMA-logging is now 'Off'!
  249. Aug  1 13:34:58.528634 P@ck-Control[842]:  [842 init_rds]; [NOTICE] RDS [01.08.2022 13:34:58.528] loaded at g_poRDS = 0x1896bbc8 g_poRdcServer = 0x83e68c4 OK!
  250. Aug  1 13:34:58.545409 P@ck-Control[842]:  [920 OpcUaThread]; [INFO] OPC-UA-thread constructor ready, m_OpcUaThread.m_bIsPlcThread == 0
  251. Aug  1 13:34:58.545636 P@ck-Control[842]:  [920 OpcUaThread]; [INFO] OPC-UA-thread finished - Server not configured!
  252. Aug  1 13:34:58.576020 startadapter.sh[732]: WebSocketConnection::send() called without connection
  253. Aug  1 13:34:58.577778 startadapter.sh[732]: WebSocketConnection::send() called without connection
  254. Aug  1 13:34:58.578745 startadapter.sh[732]: WebSocketConnection::send() called without connection
  255. Aug  1 13:34:58.579039 startadapter.sh[732]: WebSocketConnection::send() called without connection
  256. Aug  1 13:34:58.579373 startadapter.sh[732]: WebSocketConnection::send() called without connection
  257. Aug  1 13:34:58.579717 startadapter.sh[732]: WebSocketConnection::send() called without connection
  258. Aug  1 13:34:58.580001 startadapter.sh[732]: WebSocketConnection::send() called without connection
  259. Aug  1 13:34:58.580448 startadapter.sh[732]: WebSocketConnection::send() called without connection
  260. Aug  1 13:34:58.580766 startadapter.sh[732]: WebSocketConnection::send() called without connection
  261. Aug  1 13:34:58.581064 startadapter.sh[732]: WebSocketConnection::send() called without connection
  262. Aug  1 13:34:58.581572 startadapter.sh[732]: WebSocketConnection::send() called without connection
  263. Aug  1 13:34:58.582061 startadapter.sh[732]: WebSocketConnection::send() called without connection
  264. Aug  1 13:34:58.582388 startadapter.sh[732]: WebSocketConnection::send() called without connection
  265. Aug  1 13:34:58.582701 startadapter.sh[732]: WebSocketConnection::send() called without connection
  266. Aug  1 13:34:58.582995 startadapter.sh[732]: WebSocketConnection::send() called without connection
  267. Aug  1 13:34:58.583303 startadapter.sh[732]: WebSocketConnection::send() called without connection
  268. Aug  1 13:34:58.583606 startadapter.sh[732]: WebSocketConnection::send() called without connection
  269. Aug  1 13:34:58.584096 startadapter.sh[732]: WebSocketConnection::send() called without connection
  270. Aug  1 13:34:58.584420 startadapter.sh[732]: WebSocketConnection::send() called without connection
  271. Aug  1 13:34:58.584717 startadapter.sh[732]: WebSocketConnection::send() called without connection
  272. Aug  1 13:34:58.585013 startadapter.sh[732]: WebSocketConnection::send() called without connection
  273. Aug  1 13:34:58.585320 startadapter.sh[732]: WebSocketConnection::send() called without connection
  274. Aug  1 13:34:58.585615 startadapter.sh[732]: WebSocketConnection::send() called without connection
  275. Aug  1 13:34:58.585920 startadapter.sh[732]: WebSocketConnection::send() called without connection
  276. Aug  1 13:34:58.586874 startadapter.sh[732]: WebSocketConnection::send() called without connection
  277. Aug  1 13:34:58.588364 startadapter.sh[732]: WebSocketConnection::send() called without connection
  278. Aug  1 13:34:58.588686 startadapter.sh[732]: WebSocketConnection::send() called without connection
  279. Aug  1 13:34:58.588989 startadapter.sh[732]: WebSocketConnection::send() called without connection
  280. Aug  1 13:34:58.589297 startadapter.sh[732]: WebSocketConnection::send() called without connection
  281. Aug  1 13:34:58.589829 startadapter.sh[732]: WebSocketConnection::send() called without connection
  282. Aug  1 13:34:58.590135 startadapter.sh[732]: WebSocketConnection::send() called without connection
  283. Aug  1 13:34:58.590462 startadapter.sh[732]: WebSocketConnection::send() called without connection
  284. Aug  1 13:34:58.590761 startadapter.sh[732]: WebSocketConnection::send() called without connection
  285. Aug  1 13:34:58.591063 startadapter.sh[732]: WebSocketConnection::send() called without connection
  286. Aug  1 13:34:58.591377 startadapter.sh[732]: WebSocketConnection::send() called without connection
  287. Aug  1 13:34:58.591667 startadapter.sh[732]: WebSocketConnection::send() called without connection
  288. Aug  1 13:34:58.637667 P@ck-Control[842]:  [842 init_rds]; [INFO] C_RdsExecutableList  62 start; 192 startUp !
  289. Aug  1 13:34:58.638181 P@ck-Control[842]:  [842 init_rds]; [NOTICE] Init MinMaxModules
  290. Aug  1 13:34:58.658729 P@ck-Control[842]:  [924 infoMinMaxModul]; [INFO] Finish Informtask of MinMaxModul -1- HostCPU(127.0.0.1):
  291. Aug  1 13:34:58.771484 P@ck-Control[842]:  [842 init_rds]; [INFO] Initialize IP Configuration Module
  292. Aug  1 13:34:58.775161 P@ck-Control[842]:  [842 init_rds]; [INFO] IP Settings interface initialized
  293. Aug  1 13:34:58.826495 startadapter.sh[732]: WebSocket connection ws://127.0.0.1:9002 established
  294. Aug  1 13:34:58.840719 startadapter.sh[732]: #033[31merror#033[39m: Failed to fetch RDF from backend: Error: Unable to read /tmp/: Error: EISDIR: illegal operation on a directory, read
  295. Aug  1 13:34:59.019575 P@ck-Control[842]:  [918 RecvWebSocket]; [INFO] C_RdsWsHmiConnection::newLogInDetected - called by Adapter -1- WebSocket(127.0.0.1): for user: Adapter
  296. Aug  1 13:34:59.272408 REUS[848]:  [848 ReusMain]; [NOTICE] REUS connected
  297. Aug  1 13:34:59.273084 REUS[848]:  [848 ReusMain]; [INFO] Initializing tracer, set trace buffer to 20000, results will be stored here: /var/log/trace.dat
  298. Aug  1 13:34:59.581239 start-rds.sh[731]: === /opt/rovema/system/timezone.sh -- start writing /var/log/timezonelist.txt ===
  299. Aug  1 13:34:59.773312 REUS[848]:  [848 ReusMain]; [INFO] Initializing updater module...
  300. Aug  1 13:34:59.774255 REUS[848]:  [848 ReusMain]; [ERROR] No device available or device has been unplugged.
  301. Aug  1 13:34:59.775009 REUS[848]:  [848 ReusMain]; [ERROR] Update failed: No device available or device has been unplugged.
  302. Aug  1 13:34:59.776316 REUS[848]:  [848 ReusMain]; [ERROR] No device available or device has been unplugged.
  303. Aug  1 13:34:59.777387 REUS[848]:  [848 ReusMain]; [INFO] Updater initialization done
  304. Aug  1 13:34:59.777637 REUS[848]:  [848 ReusMain]; [INFO] SnapShot initialized...
  305. Aug  1 13:34:59.779316 REUS[848]:  [848 ReusMain]; [NOTICE] CopyToUSB initialized.
  306. Aug  1 13:34:59.781888 REUS[848]:  [848 ReusMain]; [NOTICE] REUS initialized successfully
  307. Aug  1 13:35:00.027856 P@ck-Control[842]:  [842 init_rds]; [INFO] C_RdsExecutableList::start 62 started
  308. Aug  1 13:35:00.028228 P@ck-Control[842]:  [842 init_rds]; [NOTICE] Start initSercos3Driver at 13:35:00.027
  309. Aug  1 13:35:00.028473 P@ck-Control[842]:  [842 init_rds]; [INFO] C_Sercos3Driver(): SoftMaster is not activ!!
  310. Aug  1 13:35:00.028643 P@ck-Control[842]:  [842 init_rds]; [INFO] Init Sercos-FPGA-Master!!
  311. Aug  1 13:35:00.028904 P@ck-Control[842]:  [842 init_rds]; [ERROR] RDS_ERROR:Sercos III works in simulation; NO real function for software-tests only!!!#012 Node: 0496 = Boards.vxhost.Hardware.Sercos3Interface
  312. Aug  1 13:35:00.032554 P@ck-Control[842]:  [842 init_rds]; [INFO] C_Sercos3Driver 40 Slaves configured!
  313. Aug  1 13:35:00.032746 P@ck-Control[842]:  [842 init_rds]; [INFO] Init initEthernetIPDriver
  314. Aug  1 13:35:00.033080 P@ck-Control[842]:  [842 init_rds]; [INFO] Start initEthernetIPDriver at 13:35:00.032
  315. Aug  1 13:35:00.053244 P@ck-Control[842]:  [1283 infoEthernetIPDriver]; [INFO] Finish Informtask of EthernetIPDriver -1- HostCPU(127.0.0.1):
  316. Aug  1 13:35:00.153392 P@ck-Control[842]:  [842 init_rds]; [INFO] initEthernetIPDriver no EthernetIP found
  317. Aug  1 13:35:00.801162 ntpd_intres[618]: DNS ptbtime1.ptb.de -> 192.53.103.108
  318. Aug  1 13:35:00.805277 ntpd_intres[618]: DNS ptbtime2.ptb.de -> 192.53.103.104
  319. Aug  1 13:35:00.807682 ntpd_intres[618]: DNS ptbtime3.ptb.de -> 192.53.103.103
  320. Aug  1 13:35:01.175275 P@ck-Control[842]:  [1289 rds_plc]; [INFO] plcInit -> BootfileDirectory = '/opt/rovema/plc'; sFileDirectory = '/opt/rovema/plc'
  321. Aug  1 13:35:01.181423 REUS[848]:  [868 tRdcBaREUS]; [INFO] Watchdog initialized
  322. Aug  1 13:35:01.182745 P@ck-Control[842]:  [1292 SercosService]; [INFO] C_Sercos3Driver::_sercosServiceThread() started
  323. Aug  1 13:35:01.182967 REUS[848]:  [1290 WatchDog]; [NOTICE] (/proc/..) is running and will be monitored by watchdog.
  324. Aug  1 13:35:01.193315 REUS[848]:  [1290 WatchDog]; [ERROR] /bin/bash /opt/rovema/javis/startJavis.sh() is not running and will NOT be monitored by watchdog.
  325. Aug  1 13:35:01.193859 REUS[848]:  [1290 WatchDog]; [ERROR] /bin/bash /usr/bin/start-javis.sh() is not running and will NOT be monitored by watchdog.
  326. Aug  1 13:35:01.194897 P@ck-Control[842]:  [1289 rds_plc]; [INFO] Start initModBusDriver at 13:35:01.194
  327. Aug  1 13:35:01.195575 REUS[848]:  [1290 WatchDog]; [NOTICE] /bin/bash /usr/bin/startadapter.sh(/proc/732) is running and will be monitored by watchdog.
  328. Aug  1 13:35:01.196943 REUS[848]:  [1290 WatchDog]; [NOTICE] /opt/rovema/calcmod/cmk CMK(/proc/1089) is running and will be monitored by watchdog.
  329. Aug  1 13:35:01.418632 P@ck-Control[842]:  [842 init_rds]; [INFO] finish C_Sercos3Driver::startUp(); bOk = 1; timeOut = 4496; CP = 4 at 01.08.2022 13:35:01.418 ! (state 0 = 'eSercosPhaseNotInit')
  330. Aug  1 13:35:01.418991 P@ck-Control[842]:  [842 init_rds]; [INFO] Sercos Startup done
  331. Aug  1 13:35:01.726600 P@ck-Control[842]:  [1302 infoModBusDriver]; [INFO] Finish Informtask of ModBusDriver -1- HostCPU(127.0.0.1):
  332. Aug  1 13:35:01.739854 CRON[1303]: pam_unix(cron:session): session opened for user root by (uid=0)
  333. Aug  1 13:35:01.741454 CRON[1304]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
  334. Aug  1 13:35:01.746314 CRON[1303]: pam_unix(cron:session): session closed for user root
  335. Aug  1 13:35:01.827085 P@ck-Control[842]:  [1289 rds_plc]; [INFO] initModBusDriver no ModBus found
  336. Aug  1 13:35:01.827417 P@ck-Control[842]:  [1289 rds_plc]; [INFO] Start initNetXDriver at 13:35:01.826
  337. Aug  1 13:35:01.858272 P@ck-Control[842]:  [1306 infoNetXDriver]; [INFO] Finish Informtask of NetXDriver -1- HostCPU(127.0.0.1):
  338. Aug  1 13:35:01.958407 P@ck-Control[842]:  [1289 rds_plc]; [INFO] initNetXDriver no NetX found
  339. Aug  1 13:35:01.960576 P@ck-Control[842]:  [1311 plcEnableWD]; [INFO] plcWatchdogEnableTask started
  340. Aug  1 13:35:01.964720 P@ck-Control[842]:  [1289 rds_plc]; [INFO] ProConOS Linux V4.2.0245
  341. Aug  1 13:35:01.965352 P@ck-Control[842]:  [1289 rds_plc]; [INFO] --------   (c) 2013 KW-Software GmbH   D-32657 Lemgo
  342. Aug  1 13:35:01.966474 P@ck-Control[842]:  [1289 rds_plc]; [INFO] PLC-Thread with id 1289 runs on cpu 0 only!
  343. Aug  1 13:35:08.091822 systemd[1]: Time has been changed
  344. Aug  1 13:35:13.312443 P@ck-Control[842]:  [842 init_rds]; [INFO] C_RdsExecutableList::startUp 192 started
  345. Aug  1 13:35:13.527680 P@ck-Control[842]:  [1289 rds_plc]; [NOTICE] C_Dio_PowerFail: no node 'Boards.vxhost.Hardware.PowerDetection' found!
  346. Aug  1 13:35:13.528071 P@ck-Control[842]:  [1289 rds_plc]; [NOTICE] C_Dio_PowerFail: will be deleted!
  347. Aug  1 13:35:13.547801 P@ck-Control[842]:  [922 infoRdsExecutable]; [INFO] Finish Informtask of RdsExecutable -1- HostCPU(127.0.0.1):
  348. Aug  1 13:35:13.627892 P@ck-Control[842]:  [1289 rds_plc]; [NOTICE] Plc startup :
  349. Aug  1 13:35:13.638862 P@ck-Control[842]:  [1289 rds_plc]; [NOTICE] Plc startup executed
  350. Aug  1 13:35:25.820074 sshd[1425]: Accepted password for root from 10.49.20.118 port 27299 ssh2
  351. Aug  1 13:35:25.823484 sshd[1425]: pam_unix(sshd:session): session opened for user root by (uid=0)
  352. Aug  1 13:36:35.937548 pure-ftpd: (?@10.49.20.118) [INFO] New connection from 10.49.20.118
  353. Aug  1 13:36:35.997885 pure-ftpd: (?@10.49.20.118) [INFO] root is now logged in
  354. Aug  1 13:36:50.825502 pure-ftpd: (root@10.49.20.118) [NOTICE] //var/log/syslog downloaded  (32992 bytes, 80774.20KB/sec)
  355. Aug  1 13:38:16.123817 pure-ftpd: (root@10.49.20.118) [NOTICE] //var/log/syslog downloaded  (33114 bytes, 326831.19KB/sec)
  356. Aug  1 13:38:49.963789 synchwclock.sh[733]: unsynchronised
  357. Aug  1 13:38:49.964020 synchwclock.sh[733]: polling server every 64 s
  358. Aug  1 13:39:16.977275 systemd[1]: Time has been changed
  359. Aug  1 13:39:16.977640 synchwclock.sh[733]: Mon Aug  1 13:39:16 CEST 2022
  360. Aug  1 13:39:16.977892 synchwclock.sh[733]: rdate: adjust local clock by 26.994776 seconds
  361. Aug  1 13:39:29.804262 pure-ftpd: (root@10.49.20.118) [NOTICE] //var/log/sw_version.log downloaded  (1804 bytes, 30917.09KB/sec)
  362. Aug  1 13:40:39.242333 sshd[1425]: pam_unix(sshd:session): session closed for user root
  363. Aug  1 13:41:17.361996 pure-ftpd: (root@10.49.20.118) [INFO] Logout.
  364. Aug  1 13:41:28.082903 pure-ftpd: (?@10.49.20.118) [INFO] New connection from 10.49.20.118
  365. Aug  1 13:41:28.146649 pure-ftpd: (?@10.49.20.118) [INFO] root is now logged in
  366. Aug  1 13:42:17.478559 pure-ftpd: (?@10.49.20.118) [INFO] New connection from 10.49.20.118
  367. Aug  1 13:42:17.524218 pure-ftpd: (?@10.49.20.118) [INFO] root is now logged in

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