Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi 3 i

Problems with packages? Post here, using [tags] of the package name.

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Mon Jul 24, 2017 2:55 pm

After a bit of googling I found this thread:
https://www.raspberrypi.org/forums/viewtopic.php?f=28&t=11259
on the Raspberry Pi forums from 2012.

It looks like this issue has always been there. Perhaps different bootloader versions are better or worse.

On the PI/card which wasn't working I added:

hdmi_force_hotplug=1

to /boot/config.txt. That seemed to help. But then commenting it out didn't make the problem reappear...
I'll update my Pi's with this 'fix' and see what happens.
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Mon Jul 24, 2017 3:07 pm

Ok scratch that. I've had a failure with:

hdmi_force_hotplug=1

I saw it working before as I left the HDMI cable in.
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby graysky » Mon Jul 24, 2017 6:41 pm

OK, just to be clear, is the issue you're looking to reproduce the network suddenly just dropping? I believe the failure to reboot might be a 2nd issue all together.
graysky
Developer
 
Posts: 1873
Joined: Sun Jun 26, 2011 6:56 am
Location: /run/user/1000

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Tue Jul 25, 2017 2:05 am

The network is not starting because the machine is not booting.
Its not just the network that doesn't start, the USB isn't initialized either, and there is no journal log. Plugging in a monitor after the failure, results in no signal.

This looks to be a bootloader issue that used to exist back in the early days, and has reoccurred in recent bootloader updates.

The bootloader handles config.txt, and that has a whole load of HDMI options. So I suspect the bootloader HDMI detection code is sometimes failing when there is no monitor attached, and results in a hang.

On a normal boot you see disk activity (as the bootloader's load), followed by a pause, and then more disk activity as linux loads.
When the system fails to boot, you don't see the secondary disk activity.

I was hoping the serial console would provide proof of this. But after enabling the uart (in config.txt I wasn't able to reproduce the hang.

The network being down was a symptom, and because the machine is headless its the only indication you see (apart from lack of disk activity). The network was not dropping, it was not coming back up after a reboot.
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby graysky » Tue Jul 25, 2017 7:01 am

OK... my problem is different. The network just drops for me after it boots and is running. If I look at the LEDs, there is not link status shown... both lights near the LAN plug are out. If I look on the other end of the Pi3, the power is on (solid red) and the green blinks very infrequently. If I downgrade packages to the set from July 10th, it seems stable. When I update to current, the problem manifests but it's seemingly random. Some times, I have uptime for 2-3 days and other times, 10-15 min. I'd say that it was hardware, but the fact that the downgrade seems to prevent it makes me thing otherwise. Here is a list of packages that are suspect:
$this->bbcode_second_pass_code('', 'grep downgrade /var/log/pacman.log | awk '{print $5 $6 $7 $8}'
bison(3.0.4-3->3.0.4-2)
zlib(1:1.2.11-2->1:1.2.11-1)
cpupower(4.12-1->4.11-2)
curl(7.54.1-2->7.54.1-1)
dnssec-anchors(20170711-1->20170228-1)
expat(2.2.2-1->2.2.1-1)
elinks(0.13-19->0.13-18)
haveged(1.9.1-3->1.9.1-2)
libelf(0.169-2->0.169-1)
iproute2(4.12.0-2->4.12.0-1)
libarchive(3.3.2-1->3.3.1-5)
pcre(8.41-1->8.40-1)
popt(1.16-9->1.16-8)
linux-raspberrypi(4.9.39-1->4.9.36-1)
pacman(5.0.2-2->5.0.2-1)
python(3.6.2-1->3.6.1-1)
python2(2.7.13-4->2.7.13-3)
raspberrypi-bootloader(20170721-1->20170703-1)
raspberrypi-bootloader-x(20170721-1->20170703-1)
raspberrypi-firmware(20170721-1->20170613-1)
s-nail(14.9.0-1->14.8.16-2)
vim-runtime(8.0.0722-1->8.0.0628-1)
vim(8.0.0722-1->8.0.0628-1)
')
graysky
Developer
 
Posts: 1873
Joined: Sun Jun 26, 2011 6:56 am
Location: /run/user/1000

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Tue Jul 25, 2017 7:26 am

Once mine are up the link is stable. I only ever have problems after a reboot, when it comes up or doesn't.
All 5 PI's are on 24/7, and the link is stable once up. They've been up 2 days 4hrs since last boot without issue.
3 are PI'3 running 32 bit, 1 is Pi3 running 64 bit, and 1 is PI B (v6l).
All have latest updates.

I'm preallocating IP addresses based on MAC address. and using a router on an internal network, which then connects to a second internet router. So Ip address assignments and NTP time doesn't rely on an external internet connection.
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Wed Jul 26, 2017 2:57 am

Another day, another kernel update. Rebooted 5 32 bit headless PI's. Both v6l PI's came up ok, 2 of of 3 v7l (PI3) Pi's did not.
As originally stated had to remove ethernet lead to get them to boot, and then plug it in later.
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby graysky » Wed Jul 26, 2017 7:23 pm

$this->bbcode_second_pass_quote('aroberts', 'A')nother day, another kernel update. Rebooted 5 32 bit headless PI's. Both v6l PI's came up ok, 2 of of 3 v7l (PI3) Pi's did not.
As originally stated had to remove ethernet lead to get them to boot, and then plug it in later.


I am able to reproduce this... after 3 days of uptime, I updated the RPi3 (arm7l) which pulled down the 4.9.39-2 kernel. I issued the reboot command after that and found that the headless box seemingly froze. No LAN lights, no green LED, just solid red. Plugging in the HDMI at this point gave no signal. Pulling the power supply was the only cure.

The journalctl output is interesting... there are 23 separate lines that report "-- Reboot --" which is not how it should behave in my experience. Only one of those should be present (comparing back to my other boxes). Not sure if there is anything relevant, but here is the log:
$this->bbcode_second_pass_code('', '

Jul 26 15:00:40 pi3 sudo[23694]: facade : TTY=pts/0 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/pacman -Syu
Jul 26 15:00:40 pi3 sudo[23694]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:01:24 pi3 sudo[23694]: pam_unix(sudo:session): session closed for user root
Jul 26 15:01:34 pi3 sudo[24245]: facade : TTY=pts/0 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/systemctl start lxc@basepi3
Jul 26 15:01:34 pi3 sudo[24245]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:01:34 pi3 systemd[1]: Starting basepi3 LXC...
Jul 26 15:01:34 pi3 systemd-udevd[24262]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 26 15:01:34 pi3 systemd-udevd[24262]: Could not generate persistent MAC address for vethJ60YFO: No such file or directory
Jul 26 15:01:34 pi3 systemd-udevd[24264]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 26 15:01:34 pi3 kernel: br0: port 3(vethXMCWHX) entered blocking state
Jul 26 15:01:34 pi3 kernel: br0: port 3(vethXMCWHX) entered disabled state
Jul 26 15:01:34 pi3 kernel: device vethXMCWHX entered promiscuous mode
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:01:34 pi3 kernel: eth0: renamed from vethJ60YFO
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:01:34 pi3 kernel: br0: port 3(vethXMCWHX) entered blocking state
Jul 26 15:01:34 pi3 kernel: br0: port 3(vethXMCWHX) entered forwarding state
Jul 26 15:01:34 pi3 systemd-networkd[395]: vethXMCWHX: Gained carrier
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:01:34 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:01:34 pi3 systemd[1]: Started basepi3 LXC.
Jul 26 15:01:34 pi3 sudo[24245]: pam_unix(sudo:session): session closed for user root
Jul 26 15:01:45 pi3 sudo[27257]: facade : TTY=pts/0 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/lxc-ls -f
Jul 26 15:01:45 pi3 sudo[27257]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:01:45 pi3 sudo[27257]: pam_unix(sudo:session): session closed for user root
Jul 26 15:01:59 pi3 sudo[32202]: facade : TTY=pts/0 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/systemctl stop lxc@basepi3
Jul 26 15:01:59 pi3 sudo[32202]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:01:59 pi3 systemd[1]: Stopping basepi3 LXC...
Jul 26 15:02:00 pi3 systemd-networkd[395]: vethXMCWHX: Lost carrier
Jul 26 15:02:00 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:02:00 pi3 kernel: br0: port 3(vethXMCWHX) entered disabled state
Jul 26 15:02:00 pi3 kernel: device vethXMCWHX left promiscuous mode
Jul 26 15:02:00 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:02:00 pi3 kernel: br0: port 3(vethXMCWHX) entered disabled state
Jul 26 15:02:00 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:02:00 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:02:00 pi3 systemd[1]: Stopped basepi3 LXC.
Jul 26 15:02:00 pi3 sudo[32202]: pam_unix(sudo:session): session closed for user root
Jul 26 15:02:32 pi3 sudo[32421]: facade : TTY=pts/0 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/reboot
Jul 26 15:02:32 pi3 sudo[32421]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:02:32 pi3 systemd[1]: Removed slice system-lxc.slice.
Jul 26 15:02:32 pi3 systemd[1]: Stopped target Graphical Interface.
Jul 26 15:02:32 pi3 systemd[1]: Stopped target Multi-User System.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Apply cpupower configuration.
Jul 26 15:02:32 pi3 systemd[1]: Stopping pihole LXC snapshot...
Jul 26 15:02:32 pi3 systemd[1]: Stopping User Manager for UID 1000...
Jul 26 15:02:32 pi3 systemd[23644]: Stopped target Default.
Jul 26 15:02:32 pi3 systemd[23644]: Stopped target Basic System.
Jul 26 15:02:32 pi3 systemd[23644]: Stopped target Sockets.
Jul 26 15:02:32 pi3 systemd[23644]: Closed GnuPG cryptographic agent and passphrase cache.
Jul 26 15:02:32 pi3 systemd[23644]: Closed GnuPG cryptographic agent and passphrase cache (restricted).
Jul 26 15:02:32 pi3 systemd[23644]: Closed GnuPG cryptographic agent and passphrase cache (access for web browsers).
Jul 26 15:02:32 pi3 systemd[23644]: Closed GnuPG cryptographic agent (ssh-agent emulation).
Jul 26 15:02:32 pi3 systemd[23644]: Stopped target Paths.
Jul 26 15:02:32 pi3 systemd[23644]: Stopped target Timers.
Jul 26 15:02:32 pi3 systemd[23644]: Closed GnuPG network certificate management daemon.
Jul 26 15:02:32 pi3 systemd[23644]: Closed D-Bus User Message Bus Socket.
Jul 26 15:02:32 pi3 sshd[23642]: pam_unix(sshd:session): session closed for user facade
Jul 26 15:02:32 pi3 systemd[23644]: Reached target Shutdown.
Jul 26 15:02:32 pi3 haveged[396]: haveged: Stopping due to signal 15
Jul 26 15:02:32 pi3 haveged[396]: haveged starting up
Jul 26 15:02:32 pi3 sudo[32421]: pam_unix(sudo:session): session closed for user root
Jul 26 15:02:32 pi3 systemd[1]: Starting Generate shutdown-ramfs...
Jul 26 15:02:32 pi3 sshd[425]: Received signal 15; terminating.
Jul 26 15:02:32 pi3 systemd[1]: Stopped target Host and Network Name Lookups.
Jul 26 15:02:32 pi3 systemd[23645]: pam_unix(systemd-user:session): session closed for user facade
Jul 26 15:02:32 pi3 systemd[1]: Stopping Network Name Resolution...
Jul 26 15:02:32 pi3 systemd[23644]: Starting Exit the Session...
Jul 26 15:02:32 pi3 systemd[1]: Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
Jul 26 15:02:32 pi3 systemd[1]: Stopped target Login Prompts.
Jul 26 15:02:32 pi3 systemd[23644]: Received SIGRTMIN+24 from PID 32430 (kill).
Jul 26 15:02:32 pi3 systemd[1]: Stopping Getty on tty1...
Jul 26 15:02:32 pi3 systemd[1]: Stopping D-Bus System Message Bus...
Jul 26 15:02:32 pi3 systemd[1]: Stopped target Timers.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Daily man-db cache update.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Daily verification of password and group files.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Daily Cleanup of Temporary Directories.
Jul 26 15:02:32 pi3 systemd[1]: Stopping Entropy Harvesting Daemon...
Jul 26 15:02:32 pi3 systemd[1]: Stopping Session c21 of user facade.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Daily rotation of log files.
Jul 26 15:02:32 pi3 systemd[1]: Stopped target System Time Synchronized.
Jul 26 15:02:32 pi3 systemd[1]: Stopping OpenSSH Daemon...
Jul 26 15:02:32 pi3 systemd[1]: Stopped Entropy Harvesting Daemon.
Jul 26 15:02:32 pi3 systemd[1]: Stopped D-Bus System Message Bus.
Jul 26 15:02:32 pi3 systemd[1]: Stopped OpenSSH Daemon.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Network Name Resolution.
Jul 26 15:02:32 pi3 systemd[1]: Stopped Getty on tty1.
Jul 26 15:02:32 pi3 systemd[1]: Stopped User Manager for UID 1000.
Jul 26 15:02:32 pi3 sshd[23642]: pam_systemd(sshd:session): Failed to release session: Connection reset by peer
Jul 26 15:02:32 pi3 systemd-logind[399]: Failed to abandon session scope, ignoring: Transport endpoint is not connected
Jul 26 15:02:33 pi3 mkinitcpio[32425]: ==> Starting build: none
Jul 26 15:02:33 pi3 mkinitcpio[32425]: -> Running build hook: [sd-shutdown]
Jul 26 15:02:33 pi3 systemd[1]: Stopped Session c21 of user facade.
Jul 26 15:02:33 pi3 systemd[1]: Removed slice User Slice of facade.
Jul 26 15:02:33 pi3 systemd[1]: Stopping Login Service...
Jul 26 15:02:33 pi3 systemd[1]: Removed slice system-getty.slice.
Jul 26 15:02:33 pi3 systemd[1]: Stopping Permit User Sessions...
Jul 26 15:02:33 pi3 systemd[1]: Stopped Login Service.
Jul 26 15:02:33 pi3 systemd[1]: Stopped Permit User Sessions.
Jul 26 15:02:33 pi3 systemd[1]: Stopped target Remote File Systems.
Jul 26 15:02:33 pi3 mkinitcpio[32425]: ==> Build complete.
Jul 26 15:02:33 pi3 systemd[1]: Started Generate shutdown-ramfs.
Jul 26 15:02:33 pi3 systemd-networkd[395]: vethHOCGI7: Lost carrier
Jul 26 15:02:33 pi3 kernel: br0: port 2(vethHOCGI7) entered disabled state
Jul 26 15:02:33 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:02:33 pi3 kernel: device vethHOCGI7 left promiscuous mode
Jul 26 15:02:33 pi3 kernel: br0: port 2(vethHOCGI7) entered disabled state
Jul 26 15:02:33 pi3 systemd-timesyncd[375]: Synchronized to time server 209.208.79.69:123 (3.arch.pool.ntp.org).
Jul 26 15:02:34 pi3 pihole[32424]: Destroyed container piholepi3
Jul 26 15:02:34 pi3 systemd[1]: Stopped pihole LXC snapshot.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Network.
Jul 26 15:02:34 pi3 systemd[1]: Stopping Network Service...
-- Reboot --
Jul 08 16:20:24 pi3 systemd-timesyncd[364]: System clock time unset or jumped backwards, restoring from recorded timestamp: Wed 2017-07-26 15:02:34 EDT
-- Reboot --
Jul 26 15:02:34 pi3 systemd-timesyncd[375]: Network configuration changed, trying to establish connection.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Basic System.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Paths.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Slices.
Jul 26 15:02:34 pi3 systemd[1]: Removed slice User and Session Slice.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Sockets.
Jul 26 15:02:34 pi3 systemd[1]: Closed D-Bus System Message Bus Socket.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target System Initialization.
Jul 26 15:02:34 pi3 systemd[1]: Stopping Update UTMP about System Boot/Shutdown...
Jul 26 15:02:34 pi3 systemd[1]: Stopping Network Time Synchronization...
Jul 26 15:02:34 pi3 systemd[1]: Stopping Load/Save Random Seed...
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Encrypted Volumes.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Load/Save Random Seed.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Started Network Time Synchronization.
Jul 26 15:02:34 pi3 systemd[1]: Reached target System Initialization.
Jul 26 15:02:34 pi3 systemd[1]: Starting Network Service...
Jul 26 15:02:34 pi3 systemd[1]: Listening on D-Bus System Message Bus Socket.
Jul 26 15:02:34 pi3 systemd[1]: Reached target Sockets.
Jul 26 15:02:34 pi3 systemd[1]: Started Daily Cleanup of Temporary Directories.
Jul 26 15:02:34 pi3 systemd[1]: Reached target Basic System.
Jul 26 15:02:34 pi3 systemd[1]: Starting Login Service...
Jul 26 15:02:34 pi3 systemd[1]: Starting Apply cpupower configuration...
Jul 26 15:02:34 pi3 systemd[1]: Started D-Bus System Message Bus.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Stopped Update UTMP about System Boot/Shutdown.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Network Time Synchronization.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Network Service.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Apply Kernel Variables.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Load Kernel Modules.
Jul 26 15:02:34 pi3 systemd[1]: Stopped Create Volatile Files and Directories.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Local File Systems.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Started Entropy Harvesting Daemon.
Jul 26 15:02:34 pi3 systemd[1]: Reached target System Time Synchronized.
Jul 26 15:02:34 pi3 systemd[1]: Started Daily man-db cache update.
Jul 26 15:02:34 pi3 systemd[1]: Started Daily verification of password and group files.
Jul 26 15:02:34 pi3 systemd[1]: Started Daily rotation of log files.
Jul 26 15:02:34 pi3 systemd[1]: Reached target Timers.
Jul 26 15:02:34 pi3 systemd-udevd[434]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 26 15:02:34 pi3 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Jul 26 15:02:34 pi3 systemd[1]: Started Apply cpupower configuration.
Jul 26 15:02:34 pi3 systemd-networkd[401]: br0: netdev ready
Jul 26 15:02:34 pi3 systemd-networkd[401]: Enumeration completed
Jul 26 15:02:34 pi3 systemd-networkd[401]: lo: Removing non-existent address: ::1/128 (valid forever)
Jul 26 15:02:34 pi3 systemd[1]: Started Network Service.
Jul 26 15:02:34 pi3 systemd-logind[405]: New seat seat0.
Jul 26 15:02:34 pi3 systemd[1]: Started Login Service.
Jul 26 15:02:34 pi3 systemd[1]: Reached target Network.
Jul 26 15:02:34 pi3 systemd-networkd[401]: br0: IPv6 successfully enabled
Jul 26 15:02:34 pi3 kernel: IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready
Jul 26 15:02:34 pi3 kernel: IPv6: ADDRCONF(NETDEV_UP): br0: link is not ready
Jul 26 15:02:34 pi3 kernel: br0: port 1(eth0) entered blocking state
Jul 26 15:02:34 pi3 kernel: br0: port 1(eth0) entered disabled state
Jul 26 15:02:34 pi3 systemd[1]: Started OpenSSH Daemon.
Jul 26 15:02:34 pi3 kernel: device eth0 entered promiscuous mode
Jul 26 15:02:34 pi3 systemd-networkd[401]: eth0: IPv6 successfully disabled
Jul 26 15:02:34 pi3 systemd[1]: Starting Permit User Sessions...
Jul 26 15:02:34 pi3 systemd[1]: Starting pihole LXC snapshot...
Jul 26 15:02:34 pi3 systemd[1]: Starting Network Name Resolution...
Jul 26 15:02:34 pi3 kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Jul 26 15:02:34 pi3 kernel: br0: port 1(eth0) entered blocking state
Jul 26 15:02:34 pi3 kernel: br0: port 1(eth0) entered forwarding state
Jul 26 15:02:34 pi3 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): br0: link becomes ready
Jul 26 15:02:34 pi3 systemd[1]: Started Permit User Sessions.
Jul 26 15:02:34 pi3 systemd[1]: Started Getty on tty1.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Unmounting /var/lib/lxc...
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Reached target Login Prompts.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Unmounting /scratch...
Jul 26 15:02:34 pi3 systemd[1]: Unmounting Temporary Directory...
Jul 26 15:02:34 pi3 systemd[1]: Unmounting /run/user/1000...
Jul 26 15:02:34 pi3 systemd[1]: Unmounting /boot...
-- Reboot --
Jul 26 15:02:34 pi3 systemd-networkd[401]: eth0: Gained carrier
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Unmounted Temporary Directory.
Jul 26 15:02:34 pi3 systemd[1]: Stopped target Swap.
Jul 26 15:02:34 pi3 systemd[1]: Deactivating swap /dev/mmcblk0p4...
Jul 26 15:02:34 pi3 systemd[1]: Unmounted /run/user/1000.
Jul 26 15:02:34 pi3 systemd[1]: Unmounted /scratch.
-- Reboot --
Jul 26 15:02:34 pi3 systemd-networkd[401]: br0: Gained carrier
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/disk/by-uuid/61de37cb-e37f-4fc0-8e65-3db04058e872.
-- Reboot --
Jul 26 15:02:34 pi3 sshd[439]: Server listening on 0.0.0.0 port 43232.
Jul 26 15:02:34 pi3 sshd[439]: Server listening on :: port 43232.
Jul 26 15:02:34 pi3 sshd[439]: Server listening on 0.0.0.0 port 22.
Jul 26 15:02:34 pi3 sshd[439]: Server listening on :: port 22.
-- Reboot --
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/disk/by-path/platform-3f202000.sdhost-part4.
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/disk/by-partuuid/9a5c3c59-04.
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/disk/by-label/swap.
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/disk/by-id/mmc-AFGCD_0x719d83d7-part4.
Jul 26 15:02:34 pi3 systemd[1]: Unmounted /boot.
Jul 26 15:02:34 pi3 systemd[1]: Deactivated swap /dev/mmcblk0p4.
-- Reboot --
Jul 26 15:02:35 pi3 systemd-resolved[445]: Positive Trust Anchors:
Jul 26 15:02:35 pi3 systemd-resolved[445]: . IN DS 19036 8 2 49aac11d7b6f6446702e54a1607371607a1a41855200fd2ce1cdde32f24e8fb5
Jul 26 15:02:35 pi3 systemd-resolved[445]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Jul 26 15:02:35 pi3 systemd-resolved[445]: Negative trust anchors: 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
Jul 26 15:02:35 pi3 systemd-resolved[445]: Using system hostname 'pi3'.
Jul 26 15:02:35 pi3 systemd[1]: Started Network Name Resolution.
Jul 26 15:02:35 pi3 systemd[1]: Reached target Host and Network Name Lookups.
Jul 26 15:02:35 pi3 systemd-udevd[499]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 26 15:02:35 pi3 systemd-udevd[499]: Could not generate persistent MAC address for vethQD0PX7: No such file or directory
Jul 26 15:02:35 pi3 systemd-udevd[500]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Jul 26 15:02:35 pi3 kernel: br0: port 2(vethNF3306) entered blocking state
Jul 26 15:02:35 pi3 kernel: br0: port 2(vethNF3306) entered disabled state
Jul 26 15:02:35 pi3 kernel: device vethNF3306 entered promiscuous mode
Jul 26 15:02:35 pi3 kernel: br0: port 2(vethNF3306) entered blocking state
Jul 26 15:02:35 pi3 kernel: br0: port 2(vethNF3306) entered forwarding state
Jul 26 15:02:35 pi3 kernel: eth0: renamed from vethQD0PX7
-- Reboot --
Jul 26 15:02:35 pi3 systemd[1]: Unmounted /var/lib/lxc.
Jul 26 15:02:35 pi3 systemd[1]: Reached target Unmount All Filesystems.
Jul 26 15:02:35 pi3 systemd[1]: Stopped File System Check on /dev/mmcblk0p3.
Jul 26 15:02:35 pi3 systemd[1]: Removed slice system-systemd\x2dfsck.slice.
Jul 26 15:02:35 pi3 systemd[1]: Stopped target Local File Systems (Pre).
Jul 26 15:02:35 pi3 systemd[1]: Stopped Create Static Device Nodes in /dev.
Jul 26 15:02:35 pi3 systemd[1]: Stopped Remount Root and Kernel File Systems.
Jul 26 15:02:35 pi3 systemd[1]: Reached target Shutdown.
Jul 26 15:02:35 pi3 systemd[1]: Reached target Final Step.
-- Reboot --
Jul 26 15:02:35 pi3 systemd-networkd[401]: vethNF3306: Gained carrier
-- Reboot --
Jul 26 15:02:35 pi3 systemd[1]: Starting Reboot...
Jul 26 15:02:35 pi3 systemd[1]: Shutting down.
-- Reboot --
Jul 26 15:02:35 pi3 haveged[431]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 7.1.1 CTV); collect: 128K
Jul 26 15:02:35 pi3 haveged[431]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 10/40; sz: 15404/71972
Jul 26 15:02:35 pi3 haveged[431]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99483
Jul 26 15:02:35 pi3 haveged[431]: haveged: fills: 0, generated: 0
Jul 26 15:02:35 pi3 kernel: random: crng init done
-- Reboot --
Jul 26 15:02:35 pi3 systemd[1]: Hardware watchdog 'Broadcom BCM2835 Watchdog timer', version 0
Jul 26 15:02:35 pi3 systemd[1]: Failed to set timeout to 600s: Invalid argument
Jul 26 15:02:35 pi3 kernel: systemd-shutdow: 30 output lines suppressed due to ratelimiting
-- Reboot --
Jul 26 15:02:35 pi3 systemd-networkd[401]: eth0: Lost carrier
Jul 26 15:02:35 pi3 kernel: br0: port 1(eth0) entered disabled state
-- Reboot --
Jul 26 15:02:36 pi3 systemd-shutdown[1]: Sending SIGTERM to remaining processes...
Jul 26 15:02:36 pi3 systemd-journald[215]: Journal stopped
-- Reboot --
Jul 26 15:02:36 pi3 kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC5E1
Jul 26 15:02:36 pi3 systemd-networkd[401]: eth0: Gained carrier
Jul 26 15:02:36 pi3 kernel: br0: port 1(eth0) entered blocking state
Jul 26 15:02:36 pi3 kernel: br0: port 1(eth0) entered forwarding state
Jul 26 15:02:36 pi3 systemd-networkd[401]: br0: DHCPv4 address 10.1.10.149/24 via 10.1.10.1
Jul 26 15:02:36 pi3 systemd-timesyncd[364]: Network configuration changed, trying to establish connection.
Jul 26 15:02:36 pi3 dbus[412]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Jul 26 15:02:36 pi3 systemd[1]: Starting Hostname Service...
Jul 26 15:02:36 pi3 systemd-networkd[401]: br0: Gained IPv6LL
Jul 26 15:02:36 pi3 dbus[412]: [system] Successfully activated service 'org.freedesktop.hostname1'
Jul 26 15:02:36 pi3 systemd[1]: Started Hostname Service.
Jul 26 15:02:38 pi3 systemd[1]: Started pihole LXC snapshot.
Jul 26 15:02:38 pi3 systemd[1]: Reached target Multi-User System.
Jul 26 15:02:38 pi3 systemd[1]: Reached target Graphical Interface.
Jul 26 15:02:38 pi3 systemd[1]: Startup finished in 5.748s (kernel) + 9.386s (userspace) = 15.135s.
Jul 26 15:02:49 pi3 systemd-networkd[401]: br0: Configured
Jul 26 15:02:49 pi3 systemd-timesyncd[364]: Network configuration changed, trying to establish connection.
Jul 26 15:02:49 pi3 systemd-resolved[445]: Switching to DNS server 10.1.10.1 for interface br0.

<<< manually pull the plug >>>

Jul 26 15:12:17 pi3 systemd-timesyncd[364]: Synchronized to time server 72.80.171.183:123 (2.arch.pool.ntp.org).
Jul 26 15:12:22 pi3 sshd[692]: Accepted publickey for facade from 10.1.10.101 port 60146 ssh2: RSA SHA256:FiK7FMYEo+Gg7A0zNX894eX8+ct9g4w9YVEHZy65mJQ
Jul 26 15:12:22 pi3 sshd[692]: pam_unix(sshd:session): session opened for user facade by (uid=0)
Jul 26 15:12:22 pi3 systemd[1]: Created slice User Slice of facade.
Jul 26 15:12:22 pi3 systemd[1]: Starting User Manager for UID 1000...
Jul 26 15:12:22 pi3 systemd-logind[405]: New session c1 of user facade.
Jul 26 15:12:22 pi3 systemd[1]: Started Session c1 of user facade.
Jul 26 15:12:22 pi3 systemd[703]: pam_unix(systemd-user:session): session opened for user facade by (uid=0)
Jul 26 15:12:22 pi3 systemd[703]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Jul 26 15:12:22 pi3 systemd[703]: Reached target Timers.
Jul 26 15:12:22 pi3 systemd[703]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Jul 26 15:12:22 pi3 systemd[703]: Listening on GnuPG network certificate management daemon.
Jul 26 15:12:22 pi3 systemd[703]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Jul 26 15:12:22 pi3 systemd[703]: Reached target Paths.
Jul 26 15:12:22 pi3 systemd[703]: Starting D-Bus User Message Bus Socket.
Jul 26 15:12:22 pi3 systemd[703]: Listening on GnuPG cryptographic agent and passphrase cache.
Jul 26 15:12:22 pi3 systemd[703]: Listening on D-Bus User Message Bus Socket.
Jul 26 15:12:22 pi3 systemd[703]: Reached target Sockets.
Jul 26 15:12:22 pi3 systemd[703]: Reached target Basic System.
Jul 26 15:12:22 pi3 systemd[703]: Reached target Default.
Jul 26 15:12:22 pi3 systemd[703]: Startup finished in 187ms.
Jul 26 15:12:22 pi3 systemd[1]: Started User Manager for UID 1000.
Jul 26 15:12:25 pi3 sudo[757]: facade : TTY=pts/1 ; PWD=/home/facade ; USER=root ; COMMAND=/usr/bin/lxc-ls -f
Jul 26 15:12:25 pi3 sudo[757]: pam_unix(sudo:session): session opened for user root by facade(uid=0)
Jul 26 15:12:25 pi3 sudo[757]: pam_unix(sudo:session): session closed for user root
')
graysky
Developer
 
Posts: 1873
Joined: Sun Jun 26, 2011 6:56 am
Location: /run/user/1000

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby graysky » Wed Jul 26, 2017 11:24 pm

@aroberts - Update your system now... do the new systemd packages help? I am at 14/14 successful reboots with them...
graysky
Developer
 
Posts: 1873
Joined: Sun Jun 26, 2011 6:56 am
Location: /run/user/1000

Re: Arch Linux ARM v7 kernel/bootcode update on Raspberry Pi

Postby aroberts » Thu Jul 27, 2017 2:49 am

Interesting. I updated the systemd, it rebuilt initramfs, so I rebooted everything. All came back up.
I switched my SD card for the clean install card which reproduced the problem on almost every reboot.
Updated that, and it has gone 15 reboots without issue.

Looks like the updated systemd has resolved the issue. Not sure what having HDMI plugged in or not has to do with systemd though.

As I mentioned initially I first saw the issue on about July 7th, which was also a day systemd was updated 3 times...

Andrew
aroberts
 
Posts: 49
Joined: Tue Mar 15, 2016 4:32 am

PreviousNext

Return to Packages

Who is online

Users browsing this forum: No registered users and 18 guests