RevPi date changes repeatedly
Posted: 13 Jul 2020, 15:01
Dear all,
we observed issues of our software just getting stuck or freezing without any messages. We had this repeatedly over the last few weeks and thought it to be caused by various different issues.
Now, we observed that the date of the revpi itself reset repeatedly. Here an extract from /var/log/daemon.log
"Jun 25 12:54:46 RevPi39763 systemd[1846]: Stopped target Sockets.
Jun 25 12:54:46 RevPi39763 avahi-daemon[1659]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.243.10.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed D-Bus User Message Bus Socket.
Jun 25 12:54:46 RevPi39763 avahi-daemon[1659]: avahi-daemon 0.6.32 exiting.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent (access for web browsers).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent and passphrase cache.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Reached target Shutdown.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Starting Exit the Session...
Jun 25 12:44:25 RevPi39763 systemd-modules-load[136]: Inserted module 'i2c_dev'
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: e2fsck 1.43.4 (31-Jan-2017)
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Superblock last mount time (Thu Jul 2 09:47:22 2020,
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: #011now = Thu Nov 3 18:16:43 2016) is in the future.
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Fix? yes
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Superblock last write time (Thu Jul 2 09:47:31 2020,
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: #011now = Thu Nov 3 18:16:43 2016) is in the future.
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Fix? yes
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Pass 1: Checking inodes, blocks, and sizes
Jun 25 12:44:25 RevPi39763 systemd-modules-load[136]: Inserted module 'piControl'
Jun 25 12:44:25 RevPi39763 systemd[1]: Started Load Kernel Modules.
Jun 25 12:44:25 RevPi39763 systemd[1]: Started Create Static Device Nodes in /dev.
"
Please observe, how time jumped back to 12:44:25. We observed this multiple times, each one followed by the line "Inserted module 'i2c_dev'". We cannot conclude yet, that only this line appeared after the time changed. The revpi is connected to the internet. We use a RevPi Connect 3+ with 32GB and the 4.19 kernel (newest kernel, but stretch image from last year march).
Which additional data would you need to understand this issue better?
Best, Robert
we observed issues of our software just getting stuck or freezing without any messages. We had this repeatedly over the last few weeks and thought it to be caused by various different issues.
Now, we observed that the date of the revpi itself reset repeatedly. Here an extract from /var/log/daemon.log
"Jun 25 12:54:46 RevPi39763 systemd[1846]: Stopped target Sockets.
Jun 25 12:54:46 RevPi39763 avahi-daemon[1659]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.243.10.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed D-Bus User Message Bus Socket.
Jun 25 12:54:46 RevPi39763 avahi-daemon[1659]: avahi-daemon 0.6.32 exiting.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent (access for web browsers).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Jun 25 12:54:46 RevPi39763 systemd[1846]: Closed GnuPG cryptographic agent and passphrase cache.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Reached target Shutdown.
Jun 25 12:54:46 RevPi39763 systemd[1846]: Starting Exit the Session...
Jun 25 12:44:25 RevPi39763 systemd-modules-load[136]: Inserted module 'i2c_dev'
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: e2fsck 1.43.4 (31-Jan-2017)
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Superblock last mount time (Thu Jul 2 09:47:22 2020,
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: #011now = Thu Nov 3 18:16:43 2016) is in the future.
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Fix? yes
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Superblock last write time (Thu Jul 2 09:47:31 2020,
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: #011now = Thu Nov 3 18:16:43 2016) is in the future.
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Fix? yes
Jun 25 12:44:25 RevPi39763 systemd-fsck[143]: Pass 1: Checking inodes, blocks, and sizes
Jun 25 12:44:25 RevPi39763 systemd-modules-load[136]: Inserted module 'piControl'
Jun 25 12:44:25 RevPi39763 systemd[1]: Started Load Kernel Modules.
Jun 25 12:44:25 RevPi39763 systemd[1]: Started Create Static Device Nodes in /dev.
"
Please observe, how time jumped back to 12:44:25. We observed this multiple times, each one followed by the line "Inserted module 'i2c_dev'". We cannot conclude yet, that only this line appeared after the time changed. The revpi is connected to the internet. We use a RevPi Connect 3+ with 32GB and the 4.19 kernel (newest kernel, but stretch image from last year march).
Which additional data would you need to understand this issue better?
Best, Robert