Slow Boot and Shutdown On Raspberry Pi3

Hi, i decide to try repetier server as octoprint replacement because i think the touch interface is better, i download the raspberry image v14, install the touchscreen drivers and everithing  work great but the start and the shutdown is slow about 1 minute to boot and minute an a half to shut down, i tried diferents sd cards, updating the kernel etc and nothing works

is there a way to improve the boot and shutdown times???

Hardware:
Raspberry Pi3 B
Waveshare 5inch Touchscreen

Comments

  • About a minute to see the final touch interface sounds right. I want to try for V15 a different start method for x server, but starting x server, chromium and loading the app will still take some time. Espcieally chromium start seems to take a good time.

    Starting basic linux is for me 14 seconds, the rest ist login and start mentioned GUI.

    What I wonder is that shutdown takes so long. Normally that is quite fast. Can you see which part is acting slow?
  • When it shutdown the screen get stuck with this image for about a minute and 20 seconds


    And when it boot show me this error:
    [Failed] Failed to start Configure bluetooth Modems connected by uart. See systenctl status hciuart.service for details

  • Ok, the screen I never really see. Rebooting closes it too fast in my case. So stopping xserver/chromuim is fast and then comes a service I guess that slows down shutdown.

    Server can take a bit longer when it is busy processing new uploads, otherwise it will stop quite fast.
    Regarding your bluetooth message - do you use bluetooth for anything like a printer? Then that might also be a reason. Failed starts often means also waiting for start.
  • No I do not use Bluetooth in my printer and yes server closes quite fast and show the terminal screen and then take a minute or little more to finish shutong down it seams something gets stuck until it reach the time out and force closing 
  • Have you checked /var/log/syslog after reboot for hints? I think if reason gets logged then there.
  • I just Check and the syslog is empty but i think its something related with the Wifi because i connect the raspberry via ethernet and boot in 35 seconds

  • i think i'm gonna try starting fresh in another sd card

  • I just did a fresh install of Repetier Server and it boots up fairly quickly, shutdown, very slow. I can see all the stuff it's shutting down as it scrolls by in the TFT display but at the point where the Rasp Pi stops accessing the SD card and the TFT goes white takes a couple of minutes.
  • to post and update i did a fresh install on different sd, the behavior is the same
    a minute to boot when is connected via wifi and 35 seconds when is connected via ethernet

    shutdown is the same in both cases around a minute and a half
  • For starting you can run dmesg to see all bootup messages with timing to see where delay occurs. 
    Have you also timed rebooting? Is it also that slow? For me it was quite fast. After display is off it is hard to see if it is still shutting down or if blinking is more hardware related and system is already down.
  • rebooting is the same situation as shutdown, here is the boot message i post i the first one is after turn it on and the second one after reboot

    pi@Repetier-Server:~ $ dmesg
    [ 0.000000] Booting Linux on physical CPU 0x0
    [ 0.000000] Linux version 4.14.80-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1161 SMP Mon Nov 12 18:51:43 GMT 2018
    [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
    [ 0.000000] CPU: div instructions available: patching division code
    [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
    [ 0.000000] Memory policy: Data cache writealloc
    [ 0.000000] cma: Reserved 8 MiB at 0x3ac00000
    [ 0.000000] On node 0 totalpages: 242688
    [ 0.000000] free_area_init_node: node 0, pgdat 80c85280, node_mem_map ba39f000
    [ 0.000000] Normal zone: 2133 pages used for memmap
    [ 0.000000] Normal zone: 0 pages reserved
    [ 0.000000] Normal zone: 242688 pages, LIFO batch:31
    [ 0.000000] percpu: Embedded 17 pages/cpu @ba347000 s38720 r8192 d22720 u69632
    [ 0.000000] pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
    [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
    [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240555
    [ 0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait fbcon=map:10 fbcon=font:ProFont6x11 logo.nologo
    [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
    [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
    [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
    [ 0.000000] Memory: 940228K/970752K available (7168K kernel code, 576K rwdata, 2076K rodata, 1024K init, 698K bss, 22332K reserved, 8192K cma-reserved)
    [ 0.000000] Virtual kernel memory layout:
    vector : 0xffff0000 - 0xffff1000 ( 4 kB)
    fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
    vmalloc : 0xbb800000 - 0xff800000 (1088 MB)
    lowmem : 0x80000000 - 0xbb400000 ( 948 MB)
    modules : 0x7f000000 - 0x80000000 ( 16 MB)
    .text : 0x80008000 - 0x80800000 (8160 kB)
    .init : 0x80b00000 - 0x80c00000 (1024 kB)
    .data : 0x80c00000 - 0x80c9017c ( 577 kB)
    .bss : 0x80c97f04 - 0x80d468b0 ( 699 kB)
    [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
    [ 0.000000] ftrace: allocating 25288 entries in 75 pages
    [ 0.000000] Hierarchical RCU implementation.
    [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
    [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
    [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
    [ 0.000022] Switching to timer-based delay loop, resolution 52ns
    [ 0.000276] Console: colour dummy device 80x30
    [ 0.000817] console [tty1] enabled
    [ 0.000859] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
    [ 0.000900] pid_max: default: 32768 minimum: 301
    [ 0.001236] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.001269] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.002224] Disabling memory control group subsystem
    [ 0.002316] CPU: Testing write buffer coherency: ok
    [ 0.002748] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [ 0.003164] Setting up static identity map for 0x100000 - 0x10003c
    [ 0.003301] Hierarchical SRCU implementation.
    [ 0.003995] smp: Bringing up secondary CPUs ...
    [ 0.004791] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    [ 0.005635] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
    [ 0.006462] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
    [ 0.006567] smp: Brought up 1 node, 4 CPUs
    [ 0.006640] SMP: Total of 4 processors activated (153.60 BogoMIPS).
    [ 0.006661] CPU: All CPU(s) started in HYP mode.
    [ 0.006679] CPU: Virtualization extensions available.
    [ 0.007622] devtmpfs: initialized
    [ 0.017898] random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0
    [ 0.018607] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
    [ 0.018847] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [ 0.018890] futex hash table entries: 1024 (order: 4, 65536 bytes)
    [ 0.019466] pinctrl core: initialized pinctrl subsystem
    [ 0.020272] NET: Registered protocol family 16
    [ 0.022892] DMA: preallocated 1024 KiB pool for atomic coherent allocations
    [ 0.027726] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [ 0.027758] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [ 0.027979] Serial: AMBA PL011 UART driver
    [ 0.029663] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
    [ 0.030173] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
    [ 0.062361] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
    [ 0.063821] SCSI subsystem initialized
    [ 0.064063] usbcore: registered new interface driver usbfs
    [ 0.064133] usbcore: registered new interface driver hub
    [ 0.064232] usbcore: registered new device driver usb
    [ 0.070066] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-11-04 16:31
    [ 0.071401] clocksource: Switched to clocksource arch_sys_counter
    [ 0.148517] VFS: Disk quotas dquot_6.6.0
    [ 0.148629] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [ 0.148834] FS-Cache: Loaded
    [ 0.149048] CacheFiles: Loaded
    [ 0.157974] NET: Registered protocol family 2
    [ 0.158716] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    [ 0.158846] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    [ 0.159048] TCP: Hash tables configured (established 8192 bind 8192)
    [ 0.159194] UDP hash table entries: 512 (order: 2, 16384 bytes)
    [ 0.159255] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    [ 0.159499] NET: Registered protocol family 1
    [ 0.159975] RPC: Registered named UNIX socket transport module.
    [ 0.159999] RPC: Registered udp transport module.
    [ 0.160018] RPC: Registered tcp transport module.
    [ 0.160038] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [ 0.161630] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
    [ 0.164378] workingset: timestamp_bits=14 max_order=18 bucket_order=4
    [ 0.172459] FS-Cache: Netfs 'nfs' registered for caching
    [ 0.173080] NFS: Registering the id_resolver key type
    [ 0.173125] Key type id_resolver registered
    [ 0.173145] Key type id_legacy registered
    [ 0.173173] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
    [ 0.175071] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
    [ 0.175227] io scheduler noop registered
    [ 0.175250] io scheduler deadline registered (default)
    [ 0.175437] io scheduler cfq registered
    [ 0.175459] io scheduler mq-deadline registered
    [ 0.175479] io scheduler kyber registered
    [ 0.178228] BCM2708FB: allocated DMA memory fad10000
    [ 0.178273] BCM2708FB: allocated DMA channel 0 @ bb813000
    [ 0.189784] Console: switching to colour frame buffer device 100x30
    [ 0.200490] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
    [ 0.205231] bcm2835-rng 3f104000.rng: hwrng registered
    [ 0.208655] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
    [ 0.212576] vc-sm: Videocore shared memory driver
    [ 0.216226] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
    [ 0.229237] brd: module loaded
    [ 0.241327] loop: module loaded
    [ 0.244725] Loading iSCSI transport class v2.0-870.
    [ 0.248728] libphy: Fixed MDIO Bus: probed
    [ 0.252202] usbcore: registered new interface driver lan78xx
    [ 0.255729] usbcore: registered new interface driver smsc95xx
    [ 0.259164] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
    [ 0.290458] dwc_otg 3f980000.usb: base=0xf0980000
    [ 0.493991] Core Release: 2.80a
    [ 0.497219] Setting default values for core params
    [ 0.500452] Finished setting default values for core params
    [ 0.703962] Using Buffer DMA mode
    [ 0.707162] Periodic Transfer Interrupt Enhancement - disabled
    [ 0.710485] Multiprocessor Interrupt Enhancement - disabled
    [ 0.713808] OTG VER PARAM: 0, OTG VER FLAG: 0
    [ 0.717054] Dedicated Tx FIFOs mode
    [ 0.720587] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbad04000 dma = 0xfad04000 len=9024
    [ 0.727295] FIQ FSM acceleration enabled for :
    Non-periodic Split Transactions
    Periodic Split Transactions
    High-Speed Isochronous Endpoints
    Interrupt/Control Split Transaction hack enabled
    [ 0.744483] dwc_otg: Microframe scheduler enabled
    [ 0.744532] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805ea4bc
    [ 0.748048] WARN::hcd_init_fiq:460: FIQ ASM at 0x805ea824 length 36
    [ 0.751517] WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000
    [ 0.754995] dwc_otg 3f980000.usb: DWC OTG Controller
    [ 0.758360] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
    [ 0.761732] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
    [ 0.765015] Init: Port Power? op_state=1
    [ 0.768164] Init: Power Port (0)
    [ 0.771516] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    [ 0.774794] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [ 0.778087] usb usb1: Product: DWC OTG Controller
    [ 0.781331] usb usb1: Manufacturer: Linux 4.14.80-v7+ dwc_otg_hcd
    [ 0.784704] usb usb1: SerialNumber: 3f980000.usb
    [ 0.788594] hub 1-0:1.0: USB hub found
    [ 0.791919] hub 1-0:1.0: 1 port detected
    [ 0.795641] dwc_otg: FIQ enabled
    [ 0.795646] dwc_otg: NAK holdoff enabled
    [ 0.795651] dwc_otg: FIQ split-transaction FSM enabled
    [ 0.795661] Module dwc_common_port init
    [ 0.795892] usbcore: registered new interface driver usb-storage
    [ 0.799360] mousedev: PS/2 mouse device common for all mice
    [ 0.802744] IR NEC protocol handler initialized
    [ 0.806018] IR RC5(x/sz) protocol handler initialized
    [ 0.809281] IR RC6 protocol handler initialized
    [ 0.812515] IR JVC protocol handler initialized
    [ 0.815650] IR Sony protocol handler initialized
    [ 0.818787] IR SANYO protocol handler initialized
    [ 0.821841] IR Sharp protocol handler initialized
    [ 0.824703] IR MCE Keyboard/mouse protocol handler initialized
    [ 0.827601] IR XMP protocol handler initialized
    [ 0.831201] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
    [ 0.834519] bcm2835-cpufreq: min=600000 max=1200000
    [ 0.837914] sdhci: Secure Digital Host Controller Interface driver
    [ 0.841003] sdhci: Copyright(c) Pierre Ossman
    [ 0.844420] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
    [ 0.847903] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
    [ 0.851217] sdhci-pltfm: SDHCI platform and OF driver helper
    [ 0.855828] ledtrig-cpu: registered to indicate activity on CPUs
    [ 0.859222] hidraw: raw HID events driver (C) Jiri Kosina
    [ 0.862643] usbcore: registered new interface driver usbhid
    [ 0.865872] usbhid: USB HID core driver
    [ 0.869690] vchiq: vchiq_init_state: slot_zero = bad80000, is_master = 0
    [ 0.874458] [vc_sm_connected_init]: start
    [ 0.883445] [vc_sm_connected_init]: end - returning 0
    [ 0.887274] Initializing XFRM netlink socket
    [ 0.890484] NET: Registered protocol family 17
    [ 0.893790] Key type dns_resolver registered
    [ 0.897394] Registering SWP/SWPB emulation handler
    [ 0.901174] registered taskstats version 1
    [ 0.910251] uart-pl011 3f201000.serial: cts_event_workaround enabled
    [ 0.913649] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
    [ 0.918530] console [ttyS0] disabled
    [ 0.921926] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 166, base_baud = 31250000) is a 16550
    [ 1.977061] console [ttyS0] enabled
    [ 1.985071] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
    [ 1.994665] mmc-bcm2835 3f300000.mmc: DMA channel allocated
    [ 2.004446] Indeed it is in host mode hprt0 = 00021501
    [ 2.101294] random: fast init done
    [ 2.108593] sdhost: log_buf @ bad07000 (fad07000)
    [ 2.152290] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
    [ 2.163130] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    [ 2.173861] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    [ 2.185697] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
    [ 2.194688] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
    [ 2.204632] of_cfs_init
    [ 2.210402] of_cfs_init: OK
    [ 2.216936] Waiting for root device /dev/mmcblk0p2...
    [ 2.223821] usb 1-1: new high-speed USB device number 2 using dwc_otg
    [ 2.223977] Indeed it is in host mode hprt0 = 00001101
    [ 2.328967] mmc0: host does not support reading read-only switch, assuming write-enable
    [ 2.342408] mmc0: Problem switching card into high-speed mode!
    [ 2.352539] mmc0: new SDXC card at address 0001
    [ 2.360916] mmcblk0: mmc0:0001 SD64G 58.2 GiB
    [ 2.370788] mmcblk0: p1 p2
    [ 2.413822] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [ 2.423812] mmc1: new high speed SDIO card at address 0001
    [ 2.434492] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
    [ 2.460247] devtmpfs: mounted
    [ 2.461715] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
    [ 2.461724] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [ 2.462309] hub 1-1:1.0: USB hub found
    [ 2.462397] hub 1-1:1.0: 5 ports detected
    [ 2.505506] Freeing unused kernel memory: 1024K
    [ 2.781448] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
    [ 2.921729] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
    [ 2.932065] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [ 2.945459] smsc95xx v1.0.6
    [ 3.044921] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:b0:99:75
    [ 3.161450] usb 1-1.3: new high-speed USB device number 4 using dwc_otg
    [ 3.335395] usb 1-1.3: New USB device found, idVendor=045e, idProduct=0772
    [ 3.346197] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
    [ 3.357510] usb 1-1.3: Product: Microsoft® LifeCam Studio(TM)
    [ 3.367286] usb 1-1.3: Manufacturer: Microsoft
    [ 3.375792] systemd[1]: System time before build time, advancing clock.
    [ 3.387007] hid-generic 0003:045E:0772.0001: hiddev96,hidraw0: USB HID v1.01 Device [Microsoft Microsoft® LifeCam Studio(TM)] on usb-3f980000.usb-1.3/input4
    [ 3.511451] usb 1-1.5: new full-speed USB device number 5 using dwc_otg
    [ 3.615146] NET: Registered protocol family 10
    [ 3.624910] Segment Routing with IPv6
    [ 3.647130] ip_tables: (C) 2000-2006 Netfilter Core Team
    [ 3.654580] usb 1-1.5: New USB device found, idVendor=1a86, idProduct=7523
    [ 3.654590] usb 1-1.5: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [ 3.654596] usb 1-1.5: Product: USB2.0-Serial
    [ 3.718095] random: systemd: uninitialized urandom read (16 bytes read)
    [ 3.735248] 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)
    [ 3.766421] systemd[1]: Detected architecture arm.
    [ 3.804808] systemd[1]: Set hostname to <Repetier-Server>.
    [ 3.867388] random: systemd: uninitialized urandom read (16 bytes read)
    [ 3.888351] random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
    [ 4.646277] systemd[1]: Reached target Swap.
    [ 4.686486] systemd[1]: Listening on Journal Socket (/dev/log).
    [ 4.702194] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
    [ 4.725452] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
    [ 4.742889] systemd[1]: Listening on udev Kernel Socket.
    [ 4.757633] systemd[1]: Listening on Syslog Socket.
    [ 4.772320] systemd[1]: Created slice System Slice.
    [ 5.490290] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    [ 5.572719] systemd-journald[87]: Received request to flush runtime journal from PID 1
    [ 6.361906] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
    [ 6.374015] bcm2835_alsa bcm2835_alsa: card created with 8 channels
    [ 6.642808] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
    [ 6.650826] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001
    [ 6.651134] usbcore: registered new interface driver brcmfmac
    [ 6.671094] ads7846 spi0.1: spi0.1 supply vcc not found, using dummy regulator
    [ 6.672354] ads7846 spi0.1: touchscreen, irq 169
    [ 6.674693] input: ADS7846 Touchscreen as /devices/platform/soc/3f204000.spi/spi_master/spi0/spi0.1/input/input0
    [ 6.679831] media: Linux media interface: v0.10
    [ 6.727959] Linux video capture interface: v2.00
    [ 6.844984] usbcore: registered new interface driver usbserial
    [ 6.845056] usbcore: registered new interface driver usbserial_generic
    [ 6.845112] usbserial: USB Serial support registered for generic
    [ 6.917225] usbcore: registered new interface driver ch341
    [ 6.917299] usbserial: USB Serial support registered for ch341-uart
    [ 6.917383] ch341 1-1.5:1.0: ch341-uart converter detected
    [ 6.919169] usb 1-1.5: ch341-uart converter now attached to ttyUSB0
    [ 7.122048] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
    [ 7.122786] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.39 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-10-23 03:47:14
    [ 7.203909] usbcore: registered new interface driver snd-usb-audio
    [ 7.205069] uvcvideo: Found UVC 1.00 device Microsoft® LifeCam Studio(TM) (045e:0772)
    [ 7.263629] uvcvideo 1-1.3:1.0: Entity type for entity Extension 5 was not initialized!
    [ 7.263646] uvcvideo 1-1.3:1.0: Entity type for entity Processing 4 was not initialized!
    [ 7.263660] uvcvideo 1-1.3:1.0: Entity type for entity Selector 3 was not initialized!
    [ 7.263671] uvcvideo 1-1.3:1.0: Entity type for entity Camera 1 was not initialized!
    [ 7.264032] input: Microsoft® LifeCam Studio(TM): as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/input/input1
    [ 7.264443] usbcore: registered new interface driver uvcvideo
    [ 7.264449] USB Video Class driver (1.1.1)
    [ 8.455779] uart-pl011 3f201000.serial: no DMA platform data
    [ 8.837846] random: crng init done
    [ 8.837861] random: 7 urandom warning(s) missed due to ratelimiting
    [ 9.033668] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    [ 9.033895] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [ 9.571792] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
    [ 11.110183] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [ 11.133643] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.177068] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.177129] brcmfmac: power management disabled
    [ 11.709569] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.709598] brcmfmac: power management disabled
    [ 12.348641] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 13.498157] Bluetooth: Core ver 2.22
    [ 13.498240] NET: Registered protocol family 31
    [ 13.498244] Bluetooth: HCI device and connection manager initialized
    [ 13.498261] Bluetooth: HCI socket layer initialized
    [ 13.498271] Bluetooth: L2CAP socket layer initialized
    [ 13.498291] Bluetooth: SCO socket layer initialized
    [ 13.500083] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 13.500098] brcmfmac: power management disabled
    [ 13.514118] Bluetooth: HCI UART driver ver 2.3
    [ 13.514130] Bluetooth: HCI UART protocol H4 registered
    [ 13.514133] Bluetooth: HCI UART protocol Three-wire (H5) registered
    [ 13.514314] Bluetooth: HCI UART protocol Broadcom registered
    [ 13.716301] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
    [ 13.716313] Bluetooth: BNEP filters: protocol multicast
    [ 13.716332] Bluetooth: BNEP socket layer initialized
    [ 13.932061] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
    [ 15.591429] Bluetooth: hci0 command 0x1002 tx timeout
    [ 17.671446] Bluetooth: hci0 command 0x0c52 tx timeout
    [ 17.671922] Bluetooth: hci0: Frame reassembly failed (-84)
    [ 39.096271] brcmfmac: power management disabled
    pi@Repetier-Server:~ $


    after reboot


    pi@Repetier-Server:~ $ sudo dmesg
    [ 0.000000] Booting Linux on physical CPU 0x0
    [ 0.000000] Linux version 4.14.80-v7+ (dc4@dc4-XPS13-9333) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1161 SMP Mon Nov 12 18:51:43 GMT 2018
    [ 0.000000] CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
    [ 0.000000] CPU: div instructions available: patching division code
    [ 0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
    [ 0.000000] OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
    [ 0.000000] Memory policy: Data cache writealloc
    [ 0.000000] cma: Reserved 8 MiB at 0x3ac00000
    [ 0.000000] On node 0 totalpages: 242688
    [ 0.000000] free_area_init_node: node 0, pgdat 80c85280, node_mem_map ba39f000
    [ 0.000000] Normal zone: 2133 pages used for memmap
    [ 0.000000] Normal zone: 0 pages reserved
    [ 0.000000] Normal zone: 242688 pages, LIFO batch:31
    [ 0.000000] percpu: Embedded 17 pages/cpu @ba347000 s38720 r8192 d22720 u69632
    [ 0.000000] pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
    [ 0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
    [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 240555
    [ 0.000000] Kernel command line: 8250.nr_uarts=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=ttyS0,115200 console=tty1 root=/dev/mmcblk0p2 rootfstype=ext4 elevator=deadline rootwait fbcon=map:10 fbcon=font:ProFont6x11 logo.nologo
    [ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
    [ 0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
    [ 0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
    [ 0.000000] Memory: 940228K/970752K available (7168K kernel code, 576K rwdata, 2076K rodata, 1024K init, 698K bss, 22332K reserved, 8192K cma-reserved)
    [ 0.000000] Virtual kernel memory layout:
    vector : 0xffff0000 - 0xffff1000 ( 4 kB)
    fixmap : 0xffc00000 - 0xfff00000 (3072 kB)
    vmalloc : 0xbb800000 - 0xff800000 (1088 MB)
    lowmem : 0x80000000 - 0xbb400000 ( 948 MB)
    modules : 0x7f000000 - 0x80000000 ( 16 MB)
    .text : 0x80008000 - 0x80800000 (8160 kB)
    .init : 0x80b00000 - 0x80c00000 (1024 kB)
    .data : 0x80c00000 - 0x80c9017c ( 577 kB)
    .bss : 0x80c97f04 - 0x80d468b0 ( 699 kB)
    [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
    [ 0.000000] ftrace: allocating 25288 entries in 75 pages
    [ 0.000000] Hierarchical RCU implementation.
    [ 0.000000] NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
    [ 0.000000] arch_timer: cp15 timer(s) running at 19.20MHz (phys).
    [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
    [ 0.000007] sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
    [ 0.000022] Switching to timer-based delay loop, resolution 52ns
    [ 0.000276] Console: colour dummy device 80x30
    [ 0.000816] console [tty1] enabled
    [ 0.000858] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
    [ 0.000898] pid_max: default: 32768 minimum: 301
    [ 0.001235] Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.001268] Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
    [ 0.002222] Disabling memory control group subsystem
    [ 0.002317] CPU: Testing write buffer coherency: ok
    [ 0.002748] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
    [ 0.003163] Setting up static identity map for 0x100000 - 0x10003c
    [ 0.003301] Hierarchical SRCU implementation.
    [ 0.003996] smp: Bringing up secondary CPUs ...
    [ 0.004795] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
    [ 0.005630] CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
    [ 0.006451] CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
    [ 0.006557] smp: Brought up 1 node, 4 CPUs
    [ 0.006629] SMP: Total of 4 processors activated (153.60 BogoMIPS).
    [ 0.006650] CPU: All CPU(s) started in HYP mode.
    [ 0.006668] CPU: Virtualization extensions available.
    [ 0.007610] devtmpfs: initialized
    [ 0.017898] random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0
    [ 0.018606] VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
    [ 0.018846] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
    [ 0.018890] futex hash table entries: 1024 (order: 4, 65536 bytes)
    [ 0.019462] pinctrl core: initialized pinctrl subsystem
    [ 0.020272] NET: Registered protocol family 16
    [ 0.022893] DMA: preallocated 1024 KiB pool for atomic coherent allocations
    [ 0.027716] hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
    [ 0.027748] hw-breakpoint: maximum watchpoint size is 8 bytes.
    [ 0.027971] Serial: AMBA PL011 UART driver
    [ 0.029656] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
    [ 0.030163] uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
    [ 0.062376] bcm2835-dma 3f007000.dma: DMA legacy API manager at bb813000, dmachans=0x1
    [ 0.063834] SCSI subsystem initialized
    [ 0.064076] usbcore: registered new interface driver usbfs
    [ 0.064145] usbcore: registered new interface driver hub
    [ 0.064246] usbcore: registered new device driver usb
    [ 0.070069] raspberrypi-firmware soc:firmware: Attached to firmware from 2018-11-04 16:31
    [ 0.071419] clocksource: Switched to clocksource arch_sys_counter
    [ 0.148561] VFS: Disk quotas dquot_6.6.0
    [ 0.148674] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
    [ 0.148879] FS-Cache: Loaded
    [ 0.149095] CacheFiles: Loaded
    [ 0.158024] NET: Registered protocol family 2
    [ 0.158765] TCP established hash table entries: 8192 (order: 3, 32768 bytes)
    [ 0.158895] TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
    [ 0.159098] TCP: Hash tables configured (established 8192 bind 8192)
    [ 0.159244] UDP hash table entries: 512 (order: 2, 16384 bytes)
    [ 0.159305] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
    [ 0.159548] NET: Registered protocol family 1
    [ 0.160026] RPC: Registered named UNIX socket transport module.
    [ 0.160050] RPC: Registered udp transport module.
    [ 0.160069] RPC: Registered tcp transport module.
    [ 0.160089] RPC: Registered tcp NFSv4.1 backchannel transport module.
    [ 0.161684] hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
    [ 0.164435] workingset: timestamp_bits=14 max_order=18 bucket_order=4
    [ 0.172501] FS-Cache: Netfs 'nfs' registered for caching
    [ 0.173125] NFS: Registering the id_resolver key type
    [ 0.173179] Key type id_resolver registered
    [ 0.173199] Key type id_legacy registered
    [ 0.173227] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
    [ 0.175163] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
    [ 0.175326] io scheduler noop registered
    [ 0.175348] io scheduler deadline registered (default)
    [ 0.175536] io scheduler cfq registered
    [ 0.175557] io scheduler mq-deadline registered
    [ 0.175578] io scheduler kyber registered
    [ 0.178317] BCM2708FB: allocated DMA memory fad10000
    [ 0.178360] BCM2708FB: allocated DMA channel 0 @ bb813000
    [ 0.189873] Console: switching to colour frame buffer device 100x30
    [ 0.200580] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
    [ 0.205312] bcm2835-rng 3f104000.rng: hwrng registered
    [ 0.208735] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
    [ 0.212697] vc-sm: Videocore shared memory driver
    [ 0.216350] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
    [ 0.229364] brd: module loaded
    [ 0.241491] loop: module loaded
    [ 0.244856] Loading iSCSI transport class v2.0-870.
    [ 0.248851] libphy: Fixed MDIO Bus: probed
    [ 0.252335] usbcore: registered new interface driver lan78xx
    [ 0.255871] usbcore: registered new interface driver smsc95xx
    [ 0.259312] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
    [ 0.290630] dwc_otg 3f980000.usb: base=0xf0980000
    [ 0.494172] Core Release: 2.80a
    [ 0.497405] Setting default values for core params
    [ 0.500645] Finished setting default values for core params
    [ 0.704189] Using Buffer DMA mode
    [ 0.707401] Periodic Transfer Interrupt Enhancement - disabled
    [ 0.710739] Multiprocessor Interrupt Enhancement - disabled
    [ 0.714073] OTG VER PARAM: 0, OTG VER FLAG: 0
    [ 0.717329] Dedicated Tx FIFOs mode
    [ 0.720812] WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbad04000 dma = 0xfad04000 len=9024
    [ 0.727544] FIQ FSM acceleration enabled for :
    Non-periodic Split Transactions
    Periodic Split Transactions
    High-Speed Isochronous Endpoints
    Interrupt/Control Split Transaction hack enabled
    [ 0.744780] dwc_otg: Microframe scheduler enabled
    [ 0.744834] WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805ea4bc
    [ 0.748369] WARN::hcd_init_fiq:460: FIQ ASM at 0x805ea824 length 36
    [ 0.751844] WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000
    [ 0.755313] dwc_otg 3f980000.usb: DWC OTG Controller
    [ 0.758687] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
    [ 0.762068] dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
    [ 0.765355] Init: Port Power? op_state=1
    [ 0.768507] Init: Power Port (0)
    [ 0.771856] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
    [ 0.775136] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
    [ 0.778429] usb usb1: Product: DWC OTG Controller
    [ 0.781692] usb usb1: Manufacturer: Linux 4.14.80-v7+ dwc_otg_hcd
    [ 0.785048] usb usb1: SerialNumber: 3f980000.usb
    [ 0.788950] hub 1-0:1.0: USB hub found
    [ 0.792268] hub 1-0:1.0: 1 port detected
    [ 0.796030] dwc_otg: FIQ enabled
    [ 0.796035] dwc_otg: NAK holdoff enabled
    [ 0.796040] dwc_otg: FIQ split-transaction FSM enabled
    [ 0.796050] Module dwc_common_port init
    [ 0.796308] usbcore: registered new interface driver usb-storage
    [ 0.799777] mousedev: PS/2 mouse device common for all mice
    [ 0.803158] IR NEC protocol handler initialized
    [ 0.806432] IR RC5(x/sz) protocol handler initialized
    [ 0.809692] IR RC6 protocol handler initialized
    [ 0.812920] IR JVC protocol handler initialized
    [ 0.816050] IR Sony protocol handler initialized
    [ 0.819177] IR SANYO protocol handler initialized
    [ 0.822219] IR Sharp protocol handler initialized
    [ 0.825073] IR MCE Keyboard/mouse protocol handler initialized
    [ 0.827962] IR XMP protocol handler initialized
    [ 0.831591] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
    [ 0.834880] bcm2835-cpufreq: min=600000 max=1200000
    [ 0.838283] sdhci: Secure Digital Host Controller Interface driver
    [ 0.841369] sdhci: Copyright(c) Pierre Ossman
    [ 0.844771] mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
    [ 0.848238] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
    [ 0.851569] sdhci-pltfm: SDHCI platform and OF driver helper
    [ 0.856196] ledtrig-cpu: registered to indicate activity on CPUs
    [ 0.859604] hidraw: raw HID events driver (C) Jiri Kosina
    [ 0.862999] usbcore: registered new interface driver usbhid
    [ 0.866227] usbhid: USB HID core driver
    [ 0.870065] vchiq: vchiq_init_state: slot_zero = bad80000, is_master = 0
    [ 0.874845] [vc_sm_connected_init]: start
    [ 0.883817] [vc_sm_connected_init]: end - returning 0
    [ 0.887646] Initializing XFRM netlink socket
    [ 0.890856] NET: Registered protocol family 17
    [ 0.894149] Key type dns_resolver registered
    [ 0.897748] Registering SWP/SWPB emulation handler
    [ 0.901691] registered taskstats version 1
    [ 0.910638] uart-pl011 3f201000.serial: cts_event_workaround enabled
    [ 0.914052] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
    [ 0.919005] console [ttyS0] disabled
    [ 0.922405] 3f215040.serial: ttyS0 at MMIO 0x0 (irq = 166, base_baud = 31250000) is a 16550
    [ 1.977640] console [ttyS0] enabled
    [ 1.984996] Indeed it is in host mode hprt0 = 00021501
    [ 2.054704] mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
    [ 2.064344] mmc-bcm2835 3f300000.mmc: DMA channel allocated
    [ 2.100516] random: fast init done
    [ 2.132094] sdhost: log_buf @ bad07000 (fad07000)
    [ 2.178228] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
    [ 2.189031] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    [ 2.199743] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
    [ 2.211603] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
    [ 2.221443] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
    [ 2.231377] of_cfs_init
    [ 2.237170] of_cfs_init: OK
    [ 2.243698] Waiting for root device /dev/mmcblk0p2...
    [ 2.250596] usb 1-1: new high-speed USB device number 2 using dwc_otg
    [ 2.250705] Indeed it is in host mode hprt0 = 00001101
    [ 2.359048] mmc0: host does not support reading read-only switch, assuming write-enable
    [ 2.372502] mmc0: Problem switching card into high-speed mode!
    [ 2.382761] mmc0: new SDXC card at address 0001
    [ 2.391234] mmcblk0: mmc0:0001 SD64G 58.2 GiB
    [ 2.401062] mmcblk0: p1 p2
    [ 2.433684] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
    [ 2.445407] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
    [ 2.450272] mmc1: new high speed SDIO card at address 0001
    [ 2.480141] devtmpfs: mounted
    [ 2.481710] usb 1-1: New USB device found, idVendor=0424, idProduct=9514
    [ 2.481719] usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [ 2.482302] hub 1-1:1.0: USB hub found
    [ 2.482379] hub 1-1:1.0: 5 ports detected
    [ 2.525321] Freeing unused kernel memory: 1024K
    [ 2.811455] usb 1-1.1: new high-speed USB device number 3 using dwc_otg
    [ 2.951729] usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00
    [ 2.962052] usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
    [ 2.975450] smsc95xx v1.0.6
    [ 3.074724] smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:b0:99:75
    [ 3.191463] usb 1-1.3: new high-speed USB device number 4 using dwc_otg
    [ 3.365438] usb 1-1.3: New USB device found, idVendor=045e, idProduct=0772
    [ 3.376201] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0
    [ 3.387472] usb 1-1.3: Product: Microsoft® LifeCam Studio(TM)
    [ 3.397188] usb 1-1.3: Manufacturer: Microsoft
    [ 3.412571] hid-generic 0003:045E:0772.0001: hiddev96,hidraw0: USB HID v1.01 Device [Microsoft Microsoft® LifeCam Studio(TM)] on usb-3f980000.usb-1.3/input4
    [ 3.454592] systemd[1]: System time before build time, advancing clock.
    [ 3.541459] usb 1-1.5: new full-speed USB device number 5 using dwc_otg
    [ 3.674592] usb 1-1.5: New USB device found, idVendor=1a86, idProduct=7523
    [ 3.685806] usb 1-1.5: New USB device strings: Mfr=0, Product=2, SerialNumber=0
    [ 3.691184] NET: Registered protocol family 10
    [ 3.692446] Segment Routing with IPv6
    [ 3.713536] usb 1-1.5: Product: USB2.0-Serial
    [ 3.730494] ip_tables: (C) 2000-2006 Netfilter Core Team
    [ 3.770441] random: systemd: uninitialized urandom read (16 bytes read)
    [ 3.787551] 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)
    [ 3.818682] systemd[1]: Detected architecture arm.
    [ 3.856541] systemd[1]: Set hostname to <Repetier-Server>.
    [ 3.918964] random: systemd: uninitialized urandom read (16 bytes read)
    [ 3.940538] random: systemd-cryptse: uninitialized urandom read (16 bytes read)
    [ 4.678884] systemd[1]: Listening on Journal Socket (/dev/log).
    [ 4.694542] systemd[1]: Listening on udev Control Socket.
    [ 4.735577] systemd[1]: Created slice System Slice.
    [ 4.749919] systemd[1]: Created slice system-systemd\x2dfsck.slice.
    [ 4.765710] systemd[1]: Created slice system-autologin.slice.
    [ 4.780724] systemd[1]: Created slice system-serial\x2dgetty.slice.
    [ 4.795830] systemd[1]: Listening on fsck to fsckd communication Socket.
    [ 5.504736] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
    [ 5.598604] systemd-journald[87]: Received request to flush runtime journal from PID 1
    [ 6.343574] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
    [ 6.351693] bcm2835_alsa bcm2835_alsa: card created with 8 channels
    [ 6.667336] media: Linux media interface: v0.10
    [ 6.726537] brcmfmac: F1 signature read @0x18000000=0x1541a9a6
    [ 6.732103] brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001
    [ 6.732358] usbcore: registered new interface driver brcmfmac
    [ 6.734583] ads7846 spi0.1: spi0.1 supply vcc not found, using dummy regulator
    [ 6.735449] ads7846 spi0.1: touchscreen, irq 169
    [ 6.738254] input: ADS7846 Touchscreen as /devices/platform/soc/3f204000.spi/spi_master/spi0/spi0.1/input/input0
    [ 6.783231] Linux video capture interface: v2.00
    [ 6.846136] usbcore: registered new interface driver usbserial
    [ 6.846207] usbcore: registered new interface driver usbserial_generic
    [ 6.846264] usbserial: USB Serial support registered for generic
    [ 6.867309] usbcore: registered new interface driver ch341
    [ 6.867382] usbserial: USB Serial support registered for ch341-uart
    [ 6.867469] ch341 1-1.5:1.0: ch341-uart converter detected
    [ 6.869551] usb 1-1.5: ch341-uart converter now attached to ttyUSB0
    [ 7.168901] brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
    [ 7.169656] brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.39 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-10-23 03:47:14
    [ 7.199910] usbcore: registered new interface driver snd-usb-audio
    [ 7.201245] uvcvideo: Found UVC 1.00 device Microsoft® LifeCam Studio(TM) (045e:0772)
    [ 7.261980] uvcvideo 1-1.3:1.0: Entity type for entity Extension 5 was not initialized!
    [ 7.261998] uvcvideo 1-1.3:1.0: Entity type for entity Processing 4 was not initialized!
    [ 7.262009] uvcvideo 1-1.3:1.0: Entity type for entity Selector 3 was not initialized!
    [ 7.262019] uvcvideo 1-1.3:1.0: Entity type for entity Camera 1 was not initialized!
    [ 7.262387] input: Microsoft® LifeCam Studio(TM): as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/input/input1
    [ 7.264475] usbcore: registered new interface driver uvcvideo
    [ 7.264482] USB Video Class driver (1.1.1)
    [ 8.724853] uart-pl011 3f201000.serial: no DMA platform data
    [ 9.139954] random: crng init done
    [ 9.139963] random: 7 urandom warning(s) missed due to ratelimiting
    [ 9.357072] Adding 102396k swap on /var/swap. Priority:-2 extents:1 across:102396k SSFS
    [ 9.494035] smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
    [ 9.494327] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [ 11.214606] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
    [ 11.236587] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.278412] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.278430] brcmfmac: power management disabled
    [ 11.813944] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 11.813970] brcmfmac: power management disabled
    [ 12.427798] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 13.559876] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
    [ 13.559904] brcmfmac: power management disabled
    [ 13.837252] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
    [ 14.100449] Bluetooth: Core ver 2.22
    [ 14.100547] NET: Registered protocol family 31
    [ 14.100554] Bluetooth: HCI device and connection manager initialized
    [ 14.100578] Bluetooth: HCI socket layer initialized
    [ 14.100597] Bluetooth: L2CAP socket layer initialized
    [ 14.100633] Bluetooth: SCO socket layer initialized
    [ 14.120703] Bluetooth: HCI UART driver ver 2.3
    [ 14.120718] Bluetooth: HCI UART protocol H4 registered
    [ 14.120725] Bluetooth: HCI UART protocol Three-wire (H5) registered
    [ 14.120973] Bluetooth: HCI UART protocol Broadcom registered
    [ 14.394769] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
    [ 14.394777] Bluetooth: BNEP filters: protocol multicast
    [ 14.394792] Bluetooth: BNEP socket layer initialized
    [ 39.588188] brcmfmac: power management disabled
    pi@Repetier-Server:~ $



  • Except that I have power management disabled after 13 seconds and no bluetooth messages timing on startup is nearly the same. A bit faster maybe but that can different sd card speeds. So that seems ok. 

    sudo systemctl poweroff --no-block

    should give you the fastest possible shutdown. Normally a daemon gets 90 seconds to shutdown before it is forced to do so. Can't say which one in your case is taking so long. You can try to stop them manually to see if one is slow, also some might make system unusable if you do.

    sudo service RepetierServer stop

    for example stops repetier-server. Takes 2-5 seconds for me normally. It can take longer if it is computing something, but when idle it should be fast.

  • Can one power down a Pi 3 Model B via Repetier server web interface?
  • Yes, if you use our image it is already configured to do so. If you mean normal interface see in main menu top right it contains a shutdown and reboot option. If you do not use our image see in manual advanced configuration on how to set up shutdown.
  • Solved my slower start up and really slow shut down...I originally installed the server on my Rasp Pi 3 from the image download of 0.91.2 that was written to the microSD card (the armel version).

    As I noted previously, start up was slow but okay, but shut down took a couple minutes...

    Two days ago I logged on the PI and downloaded the 0.91.2 ARMHF version and installed it. So whatever that did during the install fixed the slow shut down. Takes about 15 seconds max at shut down on the Pi 3 and it's off and done. Startup is also faster by a small percentage.
  • Glad it worked and might explain why my test machine was also faster. But only difference are the server executables which are compiled for better arm processors so they work a bit faster but only on Pi 2-3+.
  • I can confirm that installing the ARMHF version solves the slow boot and slow shutdown problem, thanks @wabbitguy and @Repetier for the help 
  • Installed the latest 0.92.1 ARMHF (via Repetier Server itself; no image used) and back to the 1:30 shutdown time again. The line showing is:

    Stopped Target NFS Client Services

    And we sit there for a minute and a half and then it continues to shut down. Tried the old work around by downloading directly and installing, that didn't fix it this time.

    Any suggestions?
  • Strange. I did quite some testing and modifications to stop all threads much faster. In my tests I had no problems restarting quickly when server was idle. If some slow operation is in progress it might delay shutting down.

    Can you test times for starting/stopping server only?
    sudo service RepetierServer stop
    sudo service RepetierServer start

    That would be the same as during startup/shutdown just only measuring time server requires.
  • edited August 27
    Repetier said:
    Strange. I did quite some testing and modifications to stop all threads much faster. In my tests I had no problems restarting quickly when server was idle. If some slow operation is in progress it might delay shutting down.

    Can you test times for starting/stopping server only?
    sudo service RepetierServer stop
    sudo service RepetierServer start

    That would be the same as during startup/shutdown just only measuring time server requires.
    Took 6 seconds for the message to show up on the Rasp Pi display that "Opps I lost my connection to the server".
    Took 4 seconds for the server to start (and connection reestablished).

    Hence, whatever is taking the two minutes to shut down is one of the other daemons.

    This is what shows on my 5" HDMI display connected to the Pi and where it sits...picking lint from its belly button...or something...;)
  • Tried reboot to see if I can see something in log like dmesg on startup but did not see anything. Didn't even see your screen as shutdown was maybe too fast.

    Anyhow I have found this:
    https://medium.com/@sbyang/slow-shut-down-of-ubuntu-18-04-e5fcc31255e2

    It looks like default timeout at shutdown for a service is 90 seconds and you can configure linux easily to reduce that e.g. to 4 seconds. Would at least give you fast shutdowns killing the blocking service Maybe this even gives a log entry so you know which service is slow, but I guess the log service gets killed as well early which is why we do not see the log for shutdown.
  • Repetier said:
    Tried reboot to see if I can see something in log like dmesg on startup but did not see anything. Didn't even see your screen as shutdown was maybe too fast.

    Anyhow I have found this:
    https://medium.com/@sbyang/slow-shut-down-of-ubuntu-18-04-e5fcc31255e2

    It looks like default timeout at shutdown for a service is 90 seconds and you can configure linux easily to reduce that e.g. to 4 seconds. Would at least give you fast shutdowns killing the blocking service Maybe this even gives a log entry so you know which service is slow, but I guess the log service gets killed as well early which is why we do not see the log for shutdown.
    That was it, thank you!
    For a record of it here..ssh into the Pi:
    $ sudo nano /etc/systemd/system.conf

    Change the DefaultTimeoutStopSec line to 4 or 5 seconds.

    #DefaultTimeoutStopSec=90s
    DefaultTimeoutStopSec=4s
    Save the changes and reboot...changes will take place after that.
                            
Sign In or Register to comment.