Closed mtpiercey closed 5 years ago
@hyperionlevelq Many thanks for your report.
Actually the output is just misleading:
[ SUB1 ] DietPi-Set_hardware > headless (disable)
: It disables headless mode since you selected VNC server, which requires this.[ OK ] DietPi-Set_hardware | Comment in /DietPi/config.txt converted to setting: #hdmi_ignore_hotplug=0
: This also states that that finally a commented entry is the result. If the setting would have been present in an active (not commented) way, the output would have been clearer to state that a setting was commented. The function we use is actually not really made to turn settings into comments, thus the misleading output. But the result is correct. Regardless I will change this to avoid confusion.So actually you should have fount the three entries in a commented way, thus HDMI should have been still active.
To check the long boot time, please paste: dmesg
And check journalctl
the entries until networking has been started.
Another minor:
Right. OK, this makes a lot of sense. I can see now that the /DietPi/config.txt
has the appropriate comments, as it should.
Although, the boot took around 7 minutes, if the journalctl
is to be believed.
A snippet from journalctl; note the time-gap at the end:
............
May 29 17:21:43 DietPi wpa_supplicant[429]: Successfully initialized wpa_supplicant
May 29 17:21:44 DietPi kernel: IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
May 29 17:21:44 DietPi kernel: brcmfmac: power management disabled
May 29 17:21:44 DietPi systemd[1]: Started Raise network interfaces.
May 29 17:21:44 DietPi systemd[1]: Reached target Network.
May 29 17:21:44 DietPi systemd[1]: Starting /etc/rc.local Compatibility...
May 29 17:21:44 DietPi systemd[1]: Starting DietPi-Boot...
May 29 17:21:44 DietPi systemd[1]: Starting Permit User Sessions...
May 29 17:21:44 DietPi systemd[1]: Started /etc/rc.local Compatibility.
May 29 17:21:44 DietPi systemd[1]: Started Permit User Sessions.
May 29 17:22:52 DietPi systemd[1]: dev-tty.device: Job dev-tty.device/start timed out.
May 29 17:22:52 DietPi systemd[1]: Timed out waiting for device dev-tty.device.
May 29 17:22:52 DietPi systemd[1]: Dependency failed for Serial Getty on tty.
May 29 17:22:52 DietPi systemd[1]: serial-getty@tty.service: Job serial-getty@tty.service/start failed with result 'dependency'.
May 29 17:22:52 DietPi systemd[1]: dev-tty.device: Job dev-tty.device/start failed with result 'timeout'.
May 29 17:28:11 DietPi kernel: random: crng init done
............
^ After this it connected to WiFi, and within another minute the boot was complete.
[ 0.000000] Booting Linux on physical CPU 0x0
[ 0.000000] Linux version 4.19.42+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1219 Tue May 14 21:16:38 BST 2019
[ 0.000000] CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d
[ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache
[ 0.000000] OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1
[ 0.000000] Memory policy: Data cache writeback
[ 0.000000] cma: Reserved 8 MiB at 0x1b400000
[ 0.000000] On node 0 totalpages: 114688
[ 0.000000] Normal zone: 1008 pages used for memmap
[ 0.000000] Normal zone: 0 pages reserved
[ 0.000000] Normal zone: 114688 pages, LIFO batch:31
[ 0.000000] random: get_random_bytes called from start_kernel+0x8c/0x49c with crng_init=0
[ 0.000000] pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 113680
[ 0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=768 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:90:72:E9 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=PARTUUID=b8ef7a27-02 rootfstype=ext4 elevator=deadline fsck.repair=yes rootwait
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Memory: 434484K/458752K available (6932K kernel code, 633K rwdata, 2068K rodata, 456K init, 797K bss, 16076K reserved, 8192K cma-reserved)
[ 0.000000] Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
vmalloc : 0xdc800000 - 0xff800000 ( 560 MB)
lowmem : 0xc0000000 - 0xdc000000 ( 448 MB)
modules : 0xbf000000 - 0xc0000000 ( 16 MB)
.text : 0x(ptrval) - 0x(ptrval) (6934 kB)
.init : 0x(ptrval) - 0x(ptrval) ( 456 kB)
.data : 0x(ptrval) - 0x(ptrval) ( 634 kB)
.bss : 0x(ptrval) - 0x(ptrval) ( 798 kB)
[ 0.000000] SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] ftrace: allocating 25044 entries in 74 pages
[ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
[ 0.000029] sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns
[ 0.000086] clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns
[ 0.000193] bcm2835: system timer (irq = 27)
[ 0.000788] Console: colour dummy device 80x30
[ 0.001421] console [tty1] enabled
[ 0.001504] Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792)
[ 0.060372] pid_max: default: 32768 minimum: 301
[ 0.060962] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.061021] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.062490] CPU: Testing write buffer coherency: ok
[ 0.063872] Setting up static identity map for 0x8200 - 0x8238
[ 0.065333] devtmpfs: initialized
[ 0.076286] VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5
[ 0.076753] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[ 0.076830] futex hash table entries: 256 (order: -1, 3072 bytes)
[ 0.078219] pinctrl core: initialized pinctrl subsystem
[ 0.079841] NET: Registered protocol family 16
[ 0.083160] DMA: preallocated 1024 KiB pool for atomic coherent allocations
[ 0.090332] hw-breakpoint: found 6 breakpoint and 1 watchpoint registers.
[ 0.090409] hw-breakpoint: maximum watchpoint size is 4 bytes.
[ 0.090737] Serial: AMBA PL011 UART driver
[ 0.094318] bcm2835-mbox 2000b880.mailbox: mailbox enabled
[ 0.138039] bcm2835-dma 20007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
[ 0.140603] SCSI subsystem initialized
[ 0.141122] usbcore: registered new interface driver usbfs
[ 0.141276] usbcore: registered new interface driver hub
[ 0.141495] usbcore: registered new device driver usb
[ 0.160973] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-03-27 15:45, variant start
[ 0.171003] raspberrypi-firmware soc:firmware: Firmware hash is 2e98b31d18547962e564bdf88e57b3df7085c29b
[ 0.183386] clocksource: Switched to clocksource timer
[ 0.284767] VFS: Disk quotas dquot_6.6.0
[ 0.284955] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[ 0.285321] FS-Cache: Loaded
[ 0.285697] CacheFiles: Loaded
[ 0.304607] NET: Registered protocol family 2
[ 0.306034] tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes)
[ 0.306160] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.306271] TCP bind hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.306390] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.306585] UDP hash table entries: 256 (order: 0, 4096 bytes)
[ 0.306652] UDP-Lite hash table entries: 256 (order: 0, 4096 bytes)
[ 0.307102] NET: Registered protocol family 1
[ 0.308260] RPC: Registered named UNIX socket transport module.
[ 0.308326] RPC: Registered udp transport module.
[ 0.308356] RPC: Registered tcp transport module.
[ 0.308383] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 0.310539] hw perfevents: no irqs for PMU, sampling events not supported
[ 0.310664] hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available
[ 0.314811] Initialise system trusted keyrings
[ 0.315271] workingset: timestamp_bits=14 max_order=17 bucket_order=3
[ 0.330090] FS-Cache: Netfs 'nfs' registered for caching
[ 0.331466] NFS: Registering the id_resolver key type
[ 0.331563] Key type id_resolver registered
[ 0.331597] Key type id_legacy registered
[ 0.331647] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[ 0.337468] Key type asymmetric registered
[ 0.337538] Asymmetric key parser 'x509' registered
[ 0.337675] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
[ 0.338192] io scheduler noop registered
[ 0.338250] io scheduler deadline registered (default)
[ 0.338870] io scheduler cfq registered
[ 0.338921] io scheduler mq-deadline registered (default)
[ 0.338954] io scheduler kyber registered
[ 0.342189] BCM2708FB: allocated DMA memory 0x5b500000
[ 0.342306] BCM2708FB: allocated DMA channel 0
[ 0.361678] Console: switching to colour frame buffer device 128x48
[ 0.379620] bcm2835-rng 20104000.rng: hwrng registered
[ 0.380137] vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB)
[ 0.381599] vc-sm: Videocore shared memory driver
[ 0.382496] gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000
[ 0.404483] brd: module loaded
[ 0.418488] loop: module loaded
[ 0.418675] Loading iSCSI transport class v2.0-870.
[ 0.419899] usbcore: registered new interface driver smsc95xx
[ 0.420161] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[ 0.448432] dwc_otg 20980000.usb: base=(ptrval)
[ 0.649081] Core Release: 2.80a
[ 0.649245] Setting default values for core params
[ 0.649452] Finished setting default values for core params
[ 0.855812] Using Buffer DMA mode
[ 0.861513] Periodic Transfer Interrupt Enhancement - disabled
[ 0.867249] Multiprocessor Interrupt Enhancement - disabled
[ 0.872974] OTG VER PARAM: 0, OTG VER FLAG: 0
[ 0.878734] Dedicated Tx FIFOs mode
[ 0.885446] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = db514000 dma = 0x5b514000 len=9024
[ 0.891624] FIQ FSM acceleration enabled for :
Non-periodic Split Transactions
Periodic Split Transactions
High-Speed Isochronous Endpoints
Interrupt/Control Split Transaction hack enabled
[ 0.920760] dwc_otg: Microframe scheduler enabled
[ 0.921038] WARN::hcd_init_fiq:457: FIQ on core 0
[ 0.926888] WARN::hcd_init_fiq:458: FIQ ASM at c04f48a0 length 36
[ 0.932602] WARN::hcd_init_fiq:484: MPHI regs_base at f0006000
[ 0.938522] dwc_otg 20980000.usb: DWC OTG Controller
[ 0.944445] dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1
[ 0.950364] dwc_otg 20980000.usb: irq 56, io mem 0x00000000
[ 0.956291] Init: Port Power? op_state=1
[ 0.961958] Init: Power Port (0)
[ 0.968099] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
[ 0.974105] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 0.979817] usb usb1: Product: DWC OTG Controller
[ 0.985643] usb usb1: Manufacturer: Linux 4.19.42+ dwc_otg_hcd
[ 0.991445] usb usb1: SerialNumber: 20980000.usb
[ 0.998488] hub 1-0:1.0: USB hub found
[ 1.004575] hub 1-0:1.0: 1 port detected
[ 1.011524] dwc_otg: FIQ enabled
[ 1.011537] dwc_otg: NAK holdoff enabled
[ 1.011544] dwc_otg: FIQ split-transaction FSM enabled
[ 1.011563] Module dwc_common_port init
[ 1.012112] usbcore: registered new interface driver usb-storage
[ 1.018676] mousedev: PS/2 mouse device common for all mice
[ 1.026293] bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer
[ 1.032927] bcm2835-cpufreq: min=700000 max=1000000
[ 1.039792] sdhci: Secure Digital Host Controller Interface driver
[ 1.045762] sdhci: Copyright(c) Pierre Ossman
[ 1.052214] mmc-bcm2835 20300000.mmc: could not get clk, deferring probe
[ 1.058992] sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe
[ 1.065171] sdhci-pltfm: SDHCI platform and OF driver helper
[ 1.071800] ledtrig-cpu: registered to indicate activity on CPUs
[ 1.078051] hidraw: raw HID events driver (C) Jiri Kosina
[ 1.084293] usbcore: registered new interface driver usbhid
[ 1.090234] usbhid: USB HID core driver
[ 1.097233] vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
[ 1.105683] [vc_sm_connected_init]: start
[ 1.117288] [vc_sm_connected_init]: end - returning 0
[ 1.125327] Initializing XFRM netlink socket
[ 1.131331] NET: Registered protocol family 17
[ 1.137450] Key type dns_resolver registered
[ 1.145262] registered taskstats version 1
[ 1.151114] Loading compiled-in X.509 certificates
[ 1.167622] uart-pl011 20201000.serial: cts_event_workaround enabled
[ 1.173808] 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2
[ 1.182630] mmc-bcm2835 20300000.mmc: mmc_debug:0 mmc_debug2:0
[ 1.188764] mmc-bcm2835 20300000.mmc: DMA channel allocated
[ 1.222584] sdhost: log_buf @ (ptrval) (5b513000)
[ 1.229458] Indeed it is in host mode hprt0 = 00021501
[ 1.307289] random: fast init done
[ 1.337211] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
[ 1.345315] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.353060] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
[ 1.359118] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
[ 1.367271] of_cfs_init
[ 1.373341] of_cfs_init: OK
[ 1.401478] Waiting for root device PARTUUID=b8ef7a27-02...
[ 1.408395] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
[ 1.449499] mmc0: host does not support reading read-only switch, assuming write-enable
[ 1.458431] mmc0: new high speed SDHC card at address 1234
[ 1.467603] mmcblk0: mmc0:1234 SA16G 14.5 GiB
[ 1.473835] usb 1-1: new high-speed USB device number 2 using dwc_otg
[ 1.480246] Indeed it is in host mode hprt0 = 00001101
[ 1.550252] mmcblk0: p1 p2
[ 1.595940] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[ 1.602220] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[ 1.624787] devtmpfs: mounted
[ 1.637931] Freeing unused kernel memory: 456K
[ 1.644107] This architecture does not have kernel memory protection.
[ 1.650102] Run /sbin/init as init process
[ 1.679796] mmc1: new high speed SDIO card at address 0001
[ 1.724290] usb 1-1: New USB device found, idVendor=1a40, idProduct=0101, bcdDevice= 1.00
[ 1.730663] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[ 1.736865] usb 1-1: Product: USB2.0 HUB
[ 1.745672] hub 1-1:1.0: USB hub found
[ 1.753276] hub 1-1:1.0: 4 ports detected
[ 2.173523] usb 1-1.1: new full-speed USB device number 3 using dwc_otg
[ 2.275288] systemd[1]: System time before build time, advancing clock.
[ 2.407352] usb 1-1.1: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.63
[ 2.414148] usb 1-1.1: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[ 2.420276] usb 1-1.1: Product: USB2.0-Serial
[ 2.483028] NET: Registered protocol family 10
[ 2.491699] Segment Routing with IPv6
[ 2.539028] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.556598] systemd[1]: systemd 232 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[ 2.571090] systemd[1]: Detected architecture arm.
[ 2.601378] systemd[1]: Set hostname to <DietPi>.
[ 2.643551] usb 1-1.2: new high-speed USB device number 4 using dwc_otg
[ 2.689362] random: systemd: uninitialized urandom read (16 bytes read)
[ 2.890756] usb 1-1.2: New USB device found, idVendor=0b95, idProduct=1790, bcdDevice= 1.00
[ 2.897601] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 2.904418] usb 1-1.2: Product: AX88179
[ 2.911119] usb 1-1.2: Manufacturer: ASIX Elec. Corp.
[ 2.917789] usb 1-1.2: SerialNumber: 000050B629A301
[ 2.972338] random: systemd-cryptse: uninitialized urandom read (16 bytes read)
[ 3.163557] usb 1-1.4: new full-speed USB device number 5 using dwc_otg
[ 3.407754] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.62
[ 3.414879] usb 1-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
[ 3.421748] usb 1-1.4: Product: USB2.0-Serial
[ 3.825983] systemd[1]: getty-static.service: Cannot add dependency job, ignoring: Unit getty-static.service is masked.
[ 3.851783] systemd[1]: Created slice User and Session Slice.
[ 3.868664] systemd[1]: Listening on udev Kernel Socket.
[ 3.884982] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[ 3.940685] systemd[1]: Created slice System Slice.
[ 3.958270] systemd[1]: Reached target Remote File Systems.
[ 3.984432] systemd[1]: Mounting POSIX Message Queue File System...
[ 4.369813] i2c /dev entries driver
[ 7.766200] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[ 10.244931] systemd-journald[73]: Received request to flush runtime journal from PID 1
[ 10.479843] Adding 1604604k swap on /var/swap. Priority:-2 extents:8 across:1809404k SSFS
[ 11.306740] media: Linux media interface: v0.10
[ 11.344719] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.347010] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[ 11.347034] [vc_sm_connected_init]: start
[ 11.377339] [vc_sm_connected_init]: installed successfully
[ 11.464161] videodev: Linux video capture interface: v2.00
[ 11.558958] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.562803] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.583878] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[ 11.650044] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[ 12.288688] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[ 12.288711] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[ 12.312688] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[ 12.312710] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[ 12.370185] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[ 12.370209] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[ 12.763776] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[ 12.894458] usbcore: registered new interface driver usbserial_generic
[ 12.894615] usbserial: USB Serial support registered for generic
[ 12.914536] usbcore: registered new interface driver ch341
[ 12.914696] usbserial: USB Serial support registered for ch341-uart
[ 12.914837] ch341 1-1.1:1.0: ch341-uart converter detected
[ 12.959255] usb 1-1.1: ch341-uart converter now attached to ttyUSB0
[ 12.967892] ch341 1-1.4:1.0: ch341-uart converter detected
[ 12.970348] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 13.009006] usb 1-1.4: ch341-uart converter now attached to ttyUSB1
[ 13.011913] bcm2835_audio soc:audio: card created with 8 channels
[ 13.257729] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 13.610103] ax88179_178a 1-1.2:1.0 eth0: register 'ax88179_178a' at usb-20980000.usb-1.2, ASIX AX88179 USB 3.0 Gigabit Ethernet, 00:50:b6:29:a3:01
[ 13.619746] usbcore: registered new interface driver ax88179_178a
[ 13.898294] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
[ 13.910138] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[ 13.910845] usbcore: registered new interface driver brcmfmac
[ 14.204022] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
[ 14.204228] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available
[ 14.214112] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
^ the above section was in red
[ 19.957372] brcmfmac: power management disabled
[ 24.752774] brcmfmac: power management disabled
[ 25.400802] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
[ 25.400821] brcmfmac: power management disabled
[ 412.856812] random: crng init done
^ dmesg
's output until that point
Thanks for the clarification about the HDMI thing. It was definitely confusing to see that output before the Pi rebooted itself. I was afraid for a bit that I would have had to redo the entire installation since I couldn't log-in using either HDMI or SSH.
However, I think the issues I experienced had something to do with the Pi's WiFi settings, since that doesn't seem to be working right now for some reason, and the boot process seemed to be waiting for WiFi, until it finally timed out. Though, I'm still not sure why HDMI didn't work (it still isn't working, though that might be because I'm using an HDMI to VGA converter?) but I'll make another update if I figure that out.
@hyperionlevelq
May 29 17:28:11 DietPi kernel: random: crng init done
Indeed, known issue: https://github.com/MichaIng/DietPi/issues/2806
Please do: G_AGI haveged
If that resolves the issue as expected, if you don't mind, please send a dietpi-bugreport
(and paste the ID here) as I want to collect systems where this issue occurs to eventually help solving this Debian-side, or to know in which cases we should install the above package automatically via DietPi-Update. So far it looks like only PHP7.3-FPM users are affected. But not all of them, e.g. my RPi2 with PHP7.3-FPM runs fine for 1.5 years or such 🤔.
Wow. OK, yes that worked; boot time is back to normal . One G_SUDO G_AGI haveged
later, I'm back in business. Never would've figured low entropy was the problem. However, unfortunately, now dietpi-bugreport
isn't working...
[FAILED] DietPi-Bugreport | Failed to connect to SFTP server.
Please try again later or report this to DietPi forum or GitHub repo in the first place.
@hyperionlevelq Hmm perhaps a temporary overload. Please try again. Otherwise to test general curl functionality:
> testfile
curl -T testfile sftp://dietpi-survey:upload2dietpi@ssh.dietpi.com/bugreport/
Output of that on my Pi:
More details here: https://curl.haxx.se/docs/sslcerts.html
curl failed to verify the legitimacy of the server and therefore could not
establish a secure connection to it. To learn more about this situation and
how to fix it, please visit the web page mentioned above.```
@hyperionlevelq Interestingly the testfile arrived.
Hmm perhaps the host key of ssh.dietpi.com is missing. Please try:
G_CONFIG_INJECT 'ssh.dietpi.com[[:blank:]]' 'ssh.dietpi.com ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAABAQDE6aw3r6aOEqendNu376iiCHr9tGBIWPgfrLkzjXjEsHGyVSUFNnZt6pftrDeK7UX+qX4FxOwQlugG4fymOHbimRCFiv6cf7VpYg1Ednquq9TLb7/cIIbX8a6AuRmX4fjdGuqwmBq3OG7ZksFcYEFKt5U4mAJIaL8hXiM2iXjgY02LqiQY/QWATsHI4ie9ZOnwrQE+Rr6mASN1BVFuIgyHIbwX54jsFSnZ/7CdBMkuAd9B8JkxppWVYpYIFHE9oWNfjh/epdK8yv9Oo6r0w5Rb+4qaAc5g+RAaknHeV6Gp75d2lxBdCm5XknKKbGma2+/DfoE8WZTSgzXrYcRlStYN' /root/.ssh/known_hosts
G_SUDO
Ah since we apply this for root only, it needs to be run as sudo, and perhaps the connection time is too long, so please try:
> testfile
sudo curl --connect-timeout 4 --retry 1 --retry-delay 4 -T testfile sftp://dietpi-survey:upload2dietpi@ssh.dietpi.com/bugreport/
I tried re-adding the key, but I got a warning that it already existed. So, I ran the curl test as suggested, and got this:
dietpi@DietPi:~$ sudo curl --connect-timeout 4 --retry 1 --retry-delay 4 -T testfile sftp://dietpi-survey:upload2dietpi@ssh.dietpi.com/bugreport/
% Total % Received % Xferd Average Speed Time Time Time Current
Dload Upload Total Spent Left Speed
0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0
0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0
Warning: Transient problem: timeout Will retry in 4 seconds. 1 retries left.
0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0
0 0 0 0 0 0 0 0 --:--:-- 0:00:04 --:--:-- 0
curl: (28) Operation timed out after 4476 milliseconds with 0 out of 0 bytes received
@hyperionlevelq
curl: (28) Operation timed out after 4476 milliseconds with 0 out of 0 bytes received
Indeed a timeout 🤔. Please do the following:
sed -i 's/--connect-timeout 4 --retry 1 --retry-delay 4/--connect-timeout 10 --retry 1 --retry-delay 10/' /DietPi/dietpi/dietpi-bugreport
dietpi-bugreport # to send bug report
We should use the timeouts here that one can not set in via dietpi-config network options.
Sadly, another no-go...
@hyperionlevelq Would be as well a no-go to let automated scripts run unlimited time since curl/wget by default do not apply any timeout. Having one is simply required!
The values we have in the code worked very well for several years. Not sure why lately some systems, even with fast internet bandwidth, have so slow connection times. 5s for a simple sftp connection (upload not included) is the real no-go here. In my case it's 0.2s for exactly the same operation 😉.
The reaction has already been done with v6.23 by allowing to adjust the timeouts. It has simply not yet been applied to bug report since there the usual connection test function is not in use.
Everything else is out of our control. It's core Linux/Debian network with default tools that are present on any default Raspbian/Debian image.
Yeah, that makes sense, and the connection really shouldn't be taking so long. Well, I guess I can't really make a bug report, then.
@hyperionlevelq With the above it should work? https://github.com/MichaIng/DietPi/issues/2870#issuecomment-497430303
root@DietPi:/home/dietpi# sed -i 's/--connect-timeout 4 --retry 1 --retry-delay 4/--connect-timeout 10 --retry 1 --retry-delay 10/' /DietPi/dietpi/dietpi-bugreport
root@DietPi:/home/dietpi# dietpi-bugreport
[ OK ] DietPi-Bugreport | Root access verified.
[ OK ] DietPi-Bugreport | RootFS R/W access verified.
[ INFO ] DietPi-Bugreport | Packing upload archive, please wait...
[ OK ] DietPi-Bugreport | Connection test: ssh.dietpi.com
[FAILED] DietPi-Bugreport | Failed to connect to SFTP server. Please try again later or report this to DietPi forum or GitHub repo in the first place.
A check of /DietPi/dietpi/dietpi-bugreport
confirms that the updated timeout is there, yet the error persists.
@hyperionlevelq
Hmm how long does it actually take?
time curl -ILk https://ssh.dietpi.com
In case:
sed -i 's/--connect-timeout 10 --retry 1 --retry-delay 10/--connect-timeout 20 --retry 1 --retry-delay 20/' /DietPi/dietpi/dietpi-bugreport
We could actually remove the timeout from dietpi-bugreport 🤔. It is never called non-interactively. So leave it running and print hint that ctrl+c can be used to cancel.
root@DietPi:/home/dietpi# time curl -ILk https://ssh.dietpi.com
HTTP/1.1 200 OK
Date: Thu, 30 May 2019 19:07:00 GMT
Server: Apache
Last-Modified: Sun, 19 May 2019 15:45:53 GMT
ETag: "18558-5893f82c528df"
Accept-Ranges: bytes
Content-Length: 99672
Vary: Accept-Encoding
Content-Type: text/html
real 0m3.476s
user 0m0.161s
sys 0m0.050s
@hyperionlevelq Still long, but much less then 10s.
time curl -IL sftp://ssh.dietpi.com
would be the more precise check. But this should only be done a single time, since fail2ban tracks and bans too many attempts (no user/password added here for the check).
root@DietPi:/home/dietpi# time curl -IL sftp://ssh.dietpi.com
curl: (67) Authentication failure
real 0m7.344s
user 0m0.964s
sys 0m0.097s
@hyperionlevelq Ah there it is. Yeah so with the 20 above this should then work reliable. As mentioned I will remove the timeout and replace it with an info about how to exit the execution if it takes too long.
Yep, 20 will be sufficient (since that 7's from a computer that's low-power to begin with, pushed nearly to its limits, and using a satellite Internet connection).
@hyperionlevelq Ah okay satellite internet is also a good reason.
I guess you already found the dietpi-config > Network Options: Misc > G_CHECK_URL_TIMEOUT setting?
I anyway plan to do a more precise error handling for the timeouts, so it a timeout has been detected that you will directly be offered to adjust them within the error prompt. As said they need to stay for all scripts that can be called non-interactively, which includes updates, software installs (automated first run installs), time sync, dietpi-survey and such. But for bug report indeed it can be removed completely.
Ah, the G_CHECK_URL_TIMEOUT
. I had forgotten about that actually, though I do remember changing that setting being useful when a clean installation failed a while back because of a timeout.
And, yes, being able to adjust timeouts directly within error prompts would be nice, for sure.
I will reopen to now forget about the mentioned changes.
Uh. No it does not. I am sitting here, on my rpi0w with hdmi after using certbot to setup gitea. HDMI still works...
Like I noted above, it's possible that my monitor/HDMI-to-VGA converter caused one of the issues I experienced. However, I was suspecting the installation for doing it at first, since I was misled by one of the status messages it gave me before my Pi rebooted:
[ SUB1 ] DietPi-Set_hardware > headless (disable)
[ OK ] DietPi-Set_hardware | Comment in /DietPi/config.txt converted to setting: #hdmi_ignore_hotplug=0
[ OK ] DietPi-Set_hardware | Comment in /DietPi/config.txt converted to setting: #hdmi_ignore_composite=0
[ OK ] DietPi-Set_hardware | Comment in /DietPi/config.txt converted to setting: #hdmi_blanking=0
[ OK ] headless disable | Completed
However, I was experiencing the very real #2806, which greatly affected the time it took for my Pi to reboot.
Thanks for the confirmation that HDMI isn't actually affected.
Information:
DietPi version: 6.24.1
Distro version: Debian Stretch
Kernel version: Linux DietPi 4.19.42+ #1219 Tue May 14 21:16:38 BST 2019 armv6l GNU/Linux
SBC device: Raspberry Pi Zero W v1.1
Power supply used: 5V 1A USB -> 120V (Generic Digipower brick with official-looking RPi USB cable)
SDcard used: Kingston 16GB (Class 10)
Software title: Certbot installation script
Steps to reproduce:
Expected behaviour
Actual behaviour
Extra details (the full log, see the bold part at bottom for unexpected behaviour)
Like I said, I was able to log back in and change back the HDMI settings, but I wasn't expecting this and figured it was worth sharing in case anybody else experienced this.