Error message about system journal being corrupted

Technical Support
, ,
1

Hello,

when booting my custom image built with Yocto 2.8b1, I noticed the following message during boot (at 5.28, related to the system journal):

[  OK  ] Started Remount Root and Kernel File Systems.
         Starting Flush Journal to Persistent Storage...
         Starting Rebuild Hardware Database...
         Starting Create System Users...
[  OK  ] Started Create System Users.
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Create Static Device Nodes in /dev.
[    5.254438] systemd-journald[211]: Received request to flush runtime journal from PID 1
         Starting udev Kernel Device Manager...
[    5.280481] systemd-journald[211]: File /var/log/journal/8a01e534f12643c389d44ce8af014d71/system.journal corrupted or uncleanly shut down, re.
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /var/volatile...
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Mounted /var/volatile.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Rebuild Dynamic Linker Cache...
         Starting Rebuild Journal Catalog...
[  OK  ] Started Flush Journal to Persistent Storage.

I don’t think it depends on my custom image, as the same happens with the standard Toradex console image built with:

bitbake console-tdx-image

I don’t have this problem on the same hardware using my previous Image built with Yocto 2.7b3.

I would not have noticed about the problem if it wasn’t for the message when logging in with my user:

apalis-imx6-misa login: misa
Password: 
Last login: Sun Mar 18 14:09:04 UTC 2018 on ttymxc0
[   23.571221] systemd-journald[211]: File /var/log/journal/f050724bfde44a9aaf4458806b168d4c/user-1000.journal corrupted or uncleanly shut down,.
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$

My question is, is it a real problem ? Anything I can do to fix it ? It does not happen on the same hardware with 2.7b3, so I don’t think it could be the flash.

Thank you for any help.

Nicola

2

How do you power down your system? Just by cutting off power? If so - it’s a normal to have log file corrupted sometimes. Could you please try to shutdown system gracefully (using halt command) and check if you have the same issue or not.

3

Hello Alex,

I’ve always seen this problem when rebooting the system with:

sudo reboot

When shutting down the system, I usually do it with:

sudo shutdown -h now

but I have not checked if I get the same problem at the next boot.

Nicola

4

Are you sure if you are using Toradex provided Linux? There is no sudo in our image.

5

Absolutely sure.

The first extract of the booting log was from a brand new generated tdx-console image. Image flashed, logged in as root after the very first boot (only root user available), and then typed ‘reboot’ (of course no need for sudo). At the next reboot I took the log.

The second log is from my image, when logging in as user misa, because after logging I have this other message related to the user journal being corrupted (not system journal). My image has 2 users, beside root, so I have added the package sudo in my image. This is why, as user misa, I type ‘sudo reboot’ when I need to reboot.

The message related to the user journal being corrupted after login has only been seen with user misa, never with root.

6

OK. Got it.
Could you please confirm thr log file corruption after graceful shutdown?

7

After some more tests, I can confirm the following:

with 2.8.1, no matter if I try with reboot -p, reboot, halt, poweroff, shutdown, at the next restart, I have a corrupted journal.

with 2.7.3 and 2.7.5 (building my Image with the same recipes) I don’t have such a problem, when I restart the board with ‘reboot’; but of course I get the corrupted journal message if I power off the board with the physical power button.

So I start to think that there is a problem with the new 2.8 Image; as I said in one of my previous message, the “standard” console Image built with ‘bitbake console-tdx-image’ has the same problem.

What else can I do to help detecting the source of the problem ?

Thanks.

8

Could you please do shutdown using halt command, and then boot while capturing console output? Please do it with 2.8 and 2.7 if possible.

9

The following is console output of a clean shutdown for 2.7:

apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ sudo halt
Password: 
[  OK  ] Closed Load/Save RF Kill Switc         Stopping Session c1 of user misa.
[  OK  ] Stopped target System Time Synchronized.
         Stopping User Mana�         Stopping Timestamping service...
[  OK  ] Stopped Serial Getty on ttymxc0.
[  OK  ] Stopped Misa startup service.
[  OK  ] Stopped User Manager for UID 1000.
[  OK  ] Stopped Session c1 of user misa.
[  OK  ] Stopped Save/Restore Sound Card State.
[  OK  ] Stopped Timestamping service.
[  OK  ] Removed slice User Slice of misa.
[  OK  ] Stopped target Multi-User System.
         Stopping Avahi mDNS/DNS-SD Stack...
         Stopping Login Service...
         Stopping Xinetd A Powerful Replacement For Inetd...
         Stopping Network Name Resolution...
         Stopping Boa web server...
[  OK  ] Stopped target Login Prompts.
         Stopping Getty on tty1...
[  OK  ] Removed slice system-serial\x2dgetty.slice.
[  OK  ] Stopped Avahi mDNS/DNS-SD Stack.
[  OK  ] Stopped Login Service.
[  OK  ] Stopped Boa web server.
[  OK  ] Stopped Network Name Resolution.
[  OK  ] Stopped Xinetd A Powerful Replacement For Inetd.
[  OK  ] Stopped Getty on tty1.
         Stopping Permit User Sessions...
[  OK  ] Removed slice system-getty.slice.
[  OK  ] Stopped Permit User Sessions.
[  OK  ] Stopped target Remote File Systems.
[  OK  ] Stopped target Network.
         Stopping Network Service...
[  OK  ] Stopped Network Service.
         Stopping D-Bus System Message Bus...
[  OK  ] Stopped D-Bus System Message Bus.
[  OK  ] Stopped target Basic System.
[  OK  ] Stopped target Slices.
[  OK  ] Removed slice User and Session Slice.
[  OK  ] Stopped target Paths.
[  OK  ] Stopped Forward Password Requests to Wall Directory Watch.
[  OK  ] Stopped Dispatch Password Requests to Console Directory Watch.
[  OK  ] Stopped target Sockets.
[  OK  ] Closed sshd.socket.
[  OK  ] Closed Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Closed RPCbind Server Activation Socket.
[  OK  ] Closed D-Bus System Message Bus Socket.
[  OK  ] Stopped target System Initialization.
         Stopping Network Time Synchronization...
         Stopping Load/Save Screen Backlight...htness of backlight:backlight...
[  OK  ] Stopped Apply Kernel Variables.
         Stopping Load/Save Random Seed...
         Stopping Update UTMP about System Boot/Shutdown...
[  OK  ] Stopped Load Kernel Modules.
[  OK  ] Stopped Setup Virtual Console.
[  OK  ] Stopped Network Time Synchronization.
[  OK  ] Stopped Load/Save Screen Backlight Brightness of backlight:backlight.
[  OK  ] Stopped Load/Save Random Seed.
[  OK  ] Stopped Update UTMP about System Boot/Shutdown.
[  OK  ] Removed slice system-systemd\x2dbacklight.slice.
[  OK  ] Stopped Create Volatile Files and Directories.
[  OK  ] Stopped target Local File Systems.
         Unmounting /run/user/1000...
         Unmounting Temporary Directory...
         Unmounting /media/mmcblk0p1...
         Unmounting /var/volatile...
[  OK  ] Unmounted /run/user/1000.
[  OK  ] Unmounted Temporary Directory.
[  OK  ] Unmounted /media/mmcblk0p1.
[  OK  ] Unmounted /var/volatile.
[  OK  ] Stopped target Swap.
[  OK  ] Reached target Unmount All Filesystems.
[  OK  ] Stopped target Local File Systems (Pre).
[  OK  ] Stopped Remount Root and Kernel File Systems.
[  OK  ] Stopped Create Static Device Nodes in /dev.
[  OK  ] Reached target Shutdown.
[  253.288109] systemd-shutdown[1]: Sending SIGTERM to remaining processes...
[  253.304431] systemd-journald[183]: Received SIGTERM from PID 1 (systemd-shutdow).
[  253.420055] systemd-shutdown[1]: Sending SIGKILL to remaining processes...
[  253.435235] systemd-shutdown[1]: Unmounting file systems.
[  253.441154] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  253.494923] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
[  253.502881] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  253.510984] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
[  253.517059] systemd-shutdown[1]: All filesystems unmounted.
[  253.522789] systemd-shutdown[1]: Deactivating swaps.
[  253.527955] systemd-shutdown[1]: All swaps deactivated.
[  253.533314] systemd-shutdown[1]: Detaching loop devices.
[  253.543167] systemd-shutdown[1]: All loop devices detached.
[  253.548840] systemd-shutdown[1]: Detaching DM devices.
[  253.554350] systemd-shutdown[1]: All DM devices detached.
[  253.563477] systemd-shutdown[1]: Halting system.
[  256.128396] reboot: System halted

And then restarted by power cycling the Ixora board:

U-Boot 2016.11-2.7.5+g52259cf (Mar 15 2018 - 14:57:07 +0100)

CPU:   Freescale i.MX6D rev1.5 at 792 MHz
Reset cause: POR
I2C:   ready
DRAM:  512 MiB
PMIC:  device id: 0x10, revision id: 0x21, programmed
MMC:   FSL_SDHC: 0, FSL_SDHC: 1, FSL_SDHC: 2
auto-detected panel vga-rgb
Display: vga-rgb (640x480)
In:    serial
Out:   serial
Err:   serial
Model: Toradex Apalis iMX6 Dual 512MB V1.1B, Serial# 05095230
Net:   using PHY at 7
FEC [PRIME]
Hit any key to stop autoboot:  0 
Booting from internal eMMC chip...
reading imx6q-apalis-eval.dtb
50345 bytes read in 19 ms (2.5 MiB/s)
reading uImage
5209384 bytes read in 156 ms (31.8 MiB/s)
## Booting kernel from Legacy Image at 11000000 ...
   Image Name:   Linux-4.1.44-V2.7-misa+g18717e2
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    5209320 Bytes = 5 MiB
   Load Address: 10008000
   Entry Point:  10008000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 12000000
   Booting using the fdt blob at 0x12000000
   Loading Kernel Image ... OK
   Using Device Tree in place at 12000000, end 1200f4a8

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 4.1.44-V2.7-misa+g18717e2 (nlanfran@XPS-13-9370) (gcc version 6.2.1 20161016 (Linaro GCC 6.2-2016.11) ) #1 SMP Fri 8
[    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] Machine model: Toradex Apalis iMX6Q/D Module on Apalis Evaluation Board
[    0.000000] Reserved memory: created CMA memory pool at 0x1c000000, size 320 MiB
[    0.000000] Reserved memory: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] PERCPU: Embedded 12 pages/cpu @8bb1a000 s16588 r8192 d24372 u49152
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
[    0.000000] Kernel command line: enable_wait_mode=off vmalloc=400M ip=off root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait fec_mac=00:14:2d:4dM
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    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: 181132K/524288K available (6751K kernel code, 239K rwdata, 2256K rodata, 332K init, 421K bss, 15476K reserved, 327680K cm)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xa0800000 - 0xff000000   (1512 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xa0000000   ( 512 MB)
[    0.000000]     pkmap   : 0x7fe00000 - 0x80000000   (   2 MB)
[    0.000000]     modules : 0x7f000000 - 0x7fe00000   (  14 MB)
[    0.000000]       .text : 0x80008000 - 0x808d40bc   (9009 kB)
[    0.000000]       .init : 0x808d5000 - 0x80928000   ( 332 kB)
[    0.000000]       .data : 0x80928000 - 0x80963f20   ( 240 kB)
[    0.000000]        .bss : 0x80963f20 - 0x809cd6a0   ( 422 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Additional per-CPU info printed with stalls.
[    0.000000]  RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] L2C-310 erratum 769419 enabled
[    0.000000] L2C-310 enabling early BRESP for Cortex-A9
[    0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[    0.000000] L2C-310 ID prefetch enabled, offset 16 lines
[    0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[    0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB
[    0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x76470001
[    0.000000] mxc_clocksource_init 3000000
[    0.000000] Switching to timer-based delay loop, resolution 333ns
[    0.000007] sched_clock: 32 bits at 3000kHz, resolution 333ns, wraps every 715827882841ns
[    0.000023] clocksource mxc_timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 637086815595 ns
[    0.001101] Console: colour dummy device 80x30
[    0.001723] console [tty1] enabled
[    0.001746] Calibrating delay loop (skipped), value calculated using timer frequency.. 6.00 BogoMIPS (lpj=30000)
[    0.001782] pid_max: default: 32768 minimum: 301
[    0.001887] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.001913] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.002450] Initializing cgroup subsys freezer
[    0.002486] Initializing cgroup subsys debug
[    0.002521] CPU: Testing write buffer coherency: ok
[    0.002775] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.002872] Setting up static identity map for 0x10008280 - 0x100082d8
[    0.004656] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.004730] Brought up 2 CPUs
[    0.004778] SMP: Total of 2 processors activated (12.00 BogoMIPS).
[    0.004798] CPU: All CPU(s) started in SVC mode.
[    0.005225] devtmpfs: initialized
[    0.018362] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[    0.018753] clocksource jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.018809] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.037298] pinctrl core: initialized pinctrl subsystem
[    0.038409] NET: Registered protocol family 16
[    0.046537] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.069691] cpuidle: using governor ladder
[    0.099684] cpuidle: using governor menu
[    0.099965] CPU identified as i.MX6Q, silicon rev 1.5
[    0.125064] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
[    0.125109] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.126073] imx6q-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver
[    0.127431] imx-gpc 20dc000.gpc: no fsl,ldo-bypass found!
[    0.136843] usb_host_vbus: supplied by usb_host_vbus_hub
[    0.137177] vgaarb: loaded
[    0.137950] SCSI subsystem initialized
[    0.138448] usbcore: registered new interface driver usbfs
[    0.138535] usbcore: registered new interface driver hub
[    0.138628] usbcore: registered new device driver usb
[    0.139313] i2c-gpio i2c@0: using pins 80 (SDA) and 62 (SCL)
[    0.140342] i2c i2c-1: IMX I2C adapter registered
[    0.140378] i2c i2c-1: can't use DMA
[    0.141918] stmpe-i2c 2-0041: stmpe811 detected, chip id: 0x811
[    0.143813] i2c i2c-2: IMX I2C adapter registered
[    0.143849] i2c i2c-2: can't use DMA
[    0.144534] i2c i2c-3: IMX I2C adapter registered
[    0.144569] i2c i2c-3: can't use DMA
[    0.144731] Linux video capture interface: v2.00
[    0.144805] pps_core: LinuxPPS API ver. 1 registered
[    0.144827] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.144878] PTP clock support registered
[    0.159734] imx-ipuv3 2400000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.179732] imx-ipuv3 2800000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.180634] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 driver probed ipu0 csi1
[    0.180679] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 dphy version is 0x3130302a
[    0.180785] MIPI CSI2 driver module loaded
[    0.180982] Advanced Linux Sound Architecture Driver Initialized.
[    0.182648] Switched to clocksource mxc_timer1
[    0.193481] NET: Registered protocol family 2
[    0.194258] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.194329] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    0.194410] TCP: Hash tables configured (established 4096 bind 4096)
[    0.194475] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.194514] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.194693] NET: Registered protocol family 1
[    0.194989] RPC: Registered named UNIX socket transport module.
[    0.195018] RPC: Registered udp transport module.
[    0.195036] RPC: Registered tcp transport module.
[    0.195054] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.195754] CPU PMU: Failed to parse /soc/pmu/interrupt-affinity[0]
[    0.195819] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available
[    0.197460] Bus freq driver module loaded
[    0.204434] VFS: Disk quotas dquot_6.6.0
[    0.204631] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.206161] NFS: Registering the id_resolver key type
[    0.206235] Key type id_resolver registered
[    0.206256] Key type id_legacy registered
[    0.206294] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.206796] ntfs: driver 2.1.32 [Flags: R/W].
[    0.207265] fuse init (API version 7.23)
[    0.211272] io scheduler noop registered
[    0.211314] io scheduler deadline registered
[    0.211492] io scheduler cfq registered (default)
[    0.213817] backlight supply power not found, using dummy regulator
[    0.215426] MIPI DSI driver module loaded
[    0.216825] 20e0000.hdmi_video supply HDMI not found, using dummy regulator
[    0.218807] mxc_hdmi 20e0000.hdmi_video: Detected HDMI controller 0x13:0xa:0xa0:0xc1
[    0.218866] fbcvt: 1920x1080@60: CVT Name - 2.073M9
[    0.218991] mxc_sdc_fb fb@0: registered mxc display driver hdmi
[    0.233494] mxc_sdc_fb fb@0: 1920x1080 h_sync,r,l: 44,88,148  v_sync,l,u: 5,4,36 pixclock=148500000 Hz
[    0.252731] imx-ipuv3 2400000.ipu: IPU DMFC DP HIGH RESOLUTION: 1(0,1), 5B(2~5), 5F(6,7)
[    0.286613] mxc_sdc_fb fb@0: 1920x1080 h_sync,r,l: 44,88,148  v_sync,l,u: 5,4,36 pixclock=148500000 Hz
[    0.324247] Console: switching to colour frame buffer device 240x67
[    0.359119] mxc_sdc_fb fb@1: mxcfb1 is turned off!
[    0.359276] mxc_sdc_fb fb@2: mxcfb2 is turned off!
[    0.359426] mxc_sdc_fb fb@3: mxcfb3 is turned off!
[    0.360913] imx-sdma 20ec000.sdma: no iram assigned, using external mem
[    0.361344] imx-sdma 20ec000.sdma: no event needs to be remapped
[    0.361677] imx-sdma 20ec000.sdma: loaded firmware 3.3
[    0.364357] imx-sdma 20ec000.sdma: initialized
[    0.366126] pfuze100-regulator 2-0008: Full layer: 2, Metal layer: 1
[    0.366846] pfuze100-regulator 2-0008: FAB: 0, FIN: 0
[    0.366945] pfuze100-regulator 2-0008: pfuze100 found.
[    0.381252] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 27, base_baud = 5000000) is a IMX
[    1.251848] console [ttymxc0] enabled
[    1.256495] 21e8000.serial: ttymxc1 at MMIO 0x21e8000 (irq = 303, base_baud = 5000000) is a IMX
[    1.265904] 21f0000.serial: ttymxc3 at MMIO 0x21f0000 (irq = 304, base_baud = 5000000) is a IMX
[    1.275278] 21f4000.serial: ttymxc4 at MMIO 0x21f4000 (irq = 305, base_baud = 5000000) is a IMX
[    1.284983] [drm] Initialized drm 1.1.0 20060810
[    1.290080] [drm] Initialized vivante 1.0.0 20120216 on minor 0
[    1.304227] brd: module loaded
[    1.312030] loop: module loaded
[    1.316435] ahci-imx 2200000.sata: fsl,transmit-level-mV not specified, using 00000024
[    1.324543] ahci-imx 2200000.sata: fsl,transmit-boost-mdB not specified, using 00000480
[    1.332711] ahci-imx 2200000.sata: fsl,transmit-atten-16ths not specified, using 00002000
[    1.341034] ahci-imx 2200000.sata: fsl,receive-eq-mdB not specified, using 05000000
[    1.352202] ahci-imx 2200000.sata: SSS flag set, parallel bus scan disabled
[    1.359354] ahci-imx 2200000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[    1.368317] ahci-imx 2200000.sata: flags: ncq sntf stag pm led clo only pmp pio slum part ccc apst 
[    1.378783] scsi host0: ahci-imx
[    1.382404] ata1: SATA max UDMA/133 mmio [mem 0x02200000-0x02203fff] port 0x100 irq 315
[    1.392625] spi_imx 2008000.ecspi: probed
[    1.397563] spi_imx 200c000.ecspi: probed
[    1.402555] vcan: Virtual CAN interface driver
[    1.411869] CAN device driver interface
[    1.420776] 2090000.flexcan supply xceiver not found, using dummy regulator
[    1.433440] flexcan 2090000.flexcan: device registered (reg_base=a0aa0000, irq=36)
[    1.446092] 2094000.flexcan supply xceiver not found, using dummy regulator
[    1.458735] flexcan 2094000.flexcan: device registered (reg_base=a0aa8000, irq=37)
[    1.472360] 2188000.ethernet supply phy not found, using dummy regulator
[    1.503054] pps pps0: new PPS source ptp0
[    1.513330] libphy: fec_enet_mii_bus: probed
[    1.524220] fec 2188000.ethernet eth0: registered PHC device 0
[    1.535418] usbcore: registered new interface driver asix
[    1.545880] usbcore: registered new interface driver ax88179_178a
[    1.557036] usbcore: registered new interface driver cdc_ether
[    1.567878] usbcore: registered new interface driver net1080
[    1.578463] usbcore: registered new interface driver cdc_subset
[    1.589297] usbcore: registered new interface driver zaurus
[    1.599786] usbcore: registered new interface driver cdc_ncm
[    1.610265] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    1.621697] ehci-pci: EHCI PCI platform driver
[    1.631057] ehci-mxc: Freescale On-Chip EHCI Host driver
[    1.641421] usbcore: registered new interface driver usb-storage
[    1.652402] usbcore: registered new interface driver usbserial
[    1.663106] usbcore: registered new interface driver usbserial_generic
[    1.674370] usbserial: USB Serial support registered for generic
[    1.685146] usbcore: registered new interface driver ftdi_sio
[    1.695619] usbserial: USB Serial support registered for FTDI USB Serial Device
[    1.707723] usbcore: registered new interface driver pl2303
[    1.718037] usbserial: USB Serial support registered for pl2303
[    1.728658] ata1: SATA link down (SStatus 0 SControl 300)
[    1.738731] ahci-imx 2200000.sata: no device found, disabling link.
[    1.739954] 2184800.usbmisc supply vbus-wakeup not found, using dummy regulator
[    1.761500] ahci-imx 2200000.sata: pass ahci_imx..hotplug=1 to enable hotplug
[    1.777418] ci_hdrc ci_hdrc.0: EHCI Host Controller
[    1.780166] ci_hdrc ci_hdrc.1: EHCI Host Controller
[    1.780193] ci_hdrc ci_hdrc.1: new USB bus registered, assigned bus number 1
[    1.792686] ci_hdrc ci_hdrc.1: USB 2.0 started, EHCI 1.00
[    1.793505] hub 1-0:1.0: USB hub found
[    1.793540] hub 1-0:1.0: 1 port detected
[    1.794498] mousedev: PS/2 mouse device common for all mice
[    1.845331] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 2
[    1.864319] input: stmpe-ts as /devices/soc0/soc/2100000.aips-bus/21a4000.i2c/i2c-2/2-0041/stmpe-ts/input/input0
[    1.879403] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00
[    1.890455] hub 2-0:1.0: USB hub found
[    1.899009] hub 2-0:1.0: 1 port detected
[    1.910078] rtc-ds1307 1-0068: oscillator failed, set time!
[    1.920631] rtc-ds1307 1-0068: rtc core: registered m41t0 as rtc0
[    1.932174] snvs_rtc 20cc000.snvs:snvs-rtc-lp: rtc core: registered 20cc000.snvs:snvs-r as rtc1
[    1.945849] i2c /dev entries driver
[    1.955480] mxc_v4l2_output v4l2_out: V4L2 device registered as video16
[    1.967159] mxc_v4l2_output v4l2_out: V4L2 device registered as video17
[    1.980059] imx2-wdt 20bc000.wdog: timeout 60 sec (nowayout=0)
[    1.991063] sdhci: Secure Digital Host Controller Interface driver
[    2.002094] sdhci: Copyright(c) Pierre Ossman
[    2.011449] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.023094] /soc/aips-bus@02100000/usdhc@02190000: voltage-ranges unspecified
[    2.035184] sdhci-esdhc-imx 2190000.usdhc: Got CD GPIO
[    2.046605] sdhci-esdhc-imx 2190000.usdhc: No vqmmc regulator found
[    2.102810] mmc1: SDHCI controller on 2190000.usdhc [2190000.usdhc] using ADMA
[    2.112679] usb 1-1: new high-speed USB device number 2 using ci_hdrc
[    2.126573] /soc/aips-bus@02100000/usdhc@02194000: voltage-ranges unspecified
[    2.138533] sdhci-esdhc-imx 2194000.usdhc: Got CD GPIO
[    2.149856] sdhci-esdhc-imx 2194000.usdhc: No vqmmc regulator found
[    2.202794] mmc2: SDHCI controller on 2194000.usdhc [2194000.usdhc] using ADMA
[    2.215543] /soc/aips-bus@02100000/usdhc@02198000: voltage-ranges unspecified
[    2.227912] sdhci-esdhc-imx 2198000.usdhc: No vqmmc regulator found
[    2.265622] hub 1-1:1.0: USB hub found
[    2.274327] hub 1-1:1.0: 4 ports detected
[    2.282677] mmc0: SDHCI controller on 2198000.usdhc [2198000.usdhc] using ADMA
[    2.295634] mxc_vpu 2040000.vpu_fsl: VPU initialized
[    2.305985] mxc_vdoa 21e4000.vdoa: i.MX Video Data Order Adapter(VDOA) driver probed
[    2.321096] caam 2100000.caam: Entropy delay = 3200
[    2.357907] mmc0: MAN_BKOPS_EN bit is not set
[    2.386352] mmc0: new DDR MMC card at address 0001
[    2.391614] caam 2100000.caam: Instantiated RNG4 SH0
[    2.406250] mmcblk0: mmc0:0001 Q2J54A 3.64 GiB 
[    2.415845] mmcblk0boot0: mmc0:0001 Q2J54A partition 1 2.00 MiB
[    2.426883] mmcblk0boot1: mmc0:0001 Q2J54A partition 2 2.00 MiB
[    2.446552]  mmcblk0: p1 p2
[    2.452375] caam 2100000.caam: Instantiated RNG4 SH1
[    2.452386] caam 2100000.caam: device ID = 0x0a16010000000000 (Era -524)
[    2.452393] caam 2100000.caam: job rings = 2, qi = 0
[    2.557618] caam algorithms registered in /proc/crypto
[    2.571658] caam_jr 2101000.jr0: registering rng-caam
[    2.582542] snvs-secvio 20cc000.caam-snvs: can't get snvs clock
[    2.593601] snvs-secvio 20cc000.caam-snvs: violation handlers armed - non-secure state
[    2.606848] hidraw: raw HID events driver (C) Jiri Kosina
[    2.617514] usbcore: registered new interface driver usbhid
[    2.628151] usbhid: USB HID core driver
[    2.642277] stmpe-adc stmpe-adc: Initialized
[    2.653840] sgtl5000 2-000a: sgtl5000 revision 0x11
[    2.826073] imx-sgtl5000 sound: sgtl5000 <-> 2028000.ssi mapping ok
[    2.839748] imx-spdif sound-spdif: snd-soc-dummy-dai <-> 2004000.spdif mapping ok
[    2.855054] imx-audio-hdmi sound-hdmi: hdmi-hifi <-> soc:hdmi_audio@00120000 mapping ok
[    2.869555] NET: Registered protocol family 10
[    2.879992] NET: Registered protocol family 17
[    2.889486] can: controller area network core (rev 20120528 abi 9)
[    2.900764] NET: Registered protocol family 29
[    2.910276] can: raw protocol (rev 20120528)
[    2.919564] can: broadcast manager protocol (rev 20120528 t)
[    2.930312] can: netlink gateway (rev 20130117) max_hops=1
[    2.941330] Key type dns_resolver registered
[    2.952256] Registering SWP/SWPB emulation handler
[    2.963153] 1ffc000.pcie supply pcie-bus not found, using dummy regulator
[    3.301581] imx6q-pcie 1ffc000.pcie: phy link never came up
[    3.311173] imx6q-pcie 1ffc000.pcie: failed to initialize host
[    3.321067] imx6q-pcie: probe of 1ffc000.pcie failed with error -22
[    3.333630] input: gpio-keys as /devices/soc0/gpio-keys/input/input1
[    3.345573] rtc-ds1307 1-0068: hctosys: unable to read the hardware clock
[    3.364537] ALSA device list:
[    3.371428]   #0: imx6q-apalis-sgtl5000
[    3.379148]   #1: imx-spdif
[    3.385821]   #2: imx-hdmi-soc
[    3.413609] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.425728] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.436880] devtmpfs: mounted
[    3.444033] Freeing unused kernel memory: 332K
[    3.533670] systemd[1]: System time before build time, advancing clock.
[    3.559275] random: systemd urandom read with 14 bits of entropy available
[    3.575073] systemd[1]: systemd 230 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYP)
[    3.599050] systemd[1]: Detected architecture arm.

Welcome to The Ångström Distribution v2016.12!

[    3.623096] systemd[1]: Set hostname to <apalis-imx6-misa>.
[    3.811901] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    3.852986] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[    3.892785] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    3.923365] systemd[1]: Listening on Network Service Netlink Socket.
[  OK  ] Listening on Network Service Netlink Socket.
[    3.973103] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    4.003336] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    4.033114] systemd[1]: Reached target Swap.
[  OK  ] Reached target Swap.
[    4.062980] systemd[1]: Reached target Paths.
[  OK  ] Reached target Paths.
[    4.103632] systemd[1]: Created slice System Slice.
[  OK  ] Created slice System Slice.
[    4.136432] systemd[1]: Starting Export GPIOs to user space...
         Starting Export GPIOs to user space...
[    4.173601] systemd[1]: Created slice system-getty.slice.
[  OK  ] Created slice system-getty.slice.
[    4.204908] systemd[1]: Created slice system-serial\x2dgetty.slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
[    4.246554] systemd[1]: Starting File System Check on Root Device...
         Starting File System Check on Root Device...
[    4.285026] systemd[1]: Starting Setup Virtual Console...
         Starting Setup Virtual Console...
[    4.304873] systemd[1]: Starting Create list of required static device nodes for the current kernel...
         Starting Create list of required st... nodes for the current kernel...
[    4.331848] systemd[1]: Starting Load Kernel Modules...
         [    4.342668] systemd[1]: Created slice User and Session Slice.
Starting Load Kernel Modules...
[  OK  [    4.356405] systemd[1]: Reached target Slices.
] Created slice User and Session Slice.
[  OK  ] Reached target Slices.
[    4.384697] systemd[1]: Mounting Temporary Directory...
         Mounting Temporary Directory...
[    4.422944] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    4.464460] systemd[1]: Mounting Debug File System...
         Mounting Debug File System...
[    4.504266] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[    4.544477] systemd[1]: Starting Journal Service...
         Starting Journal Service...
[    4.572986] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    4.596036] systemd[1]: Mounted Debug File System.
[  OK  ] Mounted Debug File System.
[    4.622920] systemd[1]: Mounted Temporary Directory.
[  OK  ] Mounted Temporary Directory.
[    4.653142] systemd[1]: Started Journal Service.
[  OK  ] Started Journal Service.
[  OK  ] Started Export GPIOs to user space.
[  OK  ] Started File System Check on Root Device.
[  OK  ] Started Setup Virtual Console.
[  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[  OK  ] Started Load Kernel Modules.
         Mounting Configuration File System...
         Mounting FUSE Control File System...
         Starting Apply Kernel Variables...
         Starting Remount Root and Kernel File Systems...
[  OK  ] Mounted Configuration File System.
[    4.939176] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started Apply Kernel Variables.
[  OK  ] Started Remount Root and Kernel File Systems.
         Starting Flush Journal to Persistent Storage...
         Starting udev Coldplug all Devices...
         Starting Create Static Device Nodes in /dev...
[  OK  ] Started Create Static Device Nodes in /dev.
[    5.117952] systemd-journald[190]: Received request to flush runtime journal from PID 1
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /var/volatile...
         Starting udev Kernel Device Manager...
[  OK  ] Mounted /var/volatile.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Flush Journal to Persistent Storage.
         Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
         Starting Network Time Synchronization...
         Starting Update UTMP about System Boot/Shutdown...
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Created slice system-systemd\x2dbacklight.slice.
         Starting Load/Save Screen Backlight...htness of backlight:backlight...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Found device /dev/ttymxc0.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Reached target System Initialization.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
         Starting sshd.socket.
[    5.807570] adv7280 3-0021: chip found @ 0x42 (21a8000.i2c)
[    5.825399] adv7280 3-0021: no sensor pwdn pin available
[  OK  ] Listening on sshd.socket.
[  OK  ] Reached target[    5.864215] adv7280 3-0021: adv7280_probe:Analog Device adv7280 not detected -5!
 Sockets.
[  OK  ] Reached target Basic System.
[    5.900253] max9526 3-0020: Device seems not to be a MAX9526
[  OK  ] Started D-Bus System Message Bus.
[    5.946507] /soc/aips-bus@02100000/i2c@021a8000/ov5640_mipi@3c: arguments longer than property
         Starting Boa web server...
         Starting Load default USB gadget schema g1.schema...
         Starting Login Service...
[    6.213097] ov5640_read_reg:write reg error:reg=300a
[    6.222824] camera ov5640_mipi is not found
         Starting Save/Restore Sound Card State...
[  OK  ] Started Timestamping service.
         Starting Avahi mDNS/DNS-SD Stack...
[  OK  ] Started Boa web server.
[FAILED] Failed to start Load default USB gadget schema g1.schema.
See 'systemctl status usbg.service' for details.
[  OK  ] Started Save/Restore Sound Card State.
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[    6.885627] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[    6.897941] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[    6.905875] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[  OK  ] Started Login Service.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
         Starting Network Service...
[  OK  ] Reached target Sound Card.
[  OK  ] Started Network Service.
[  OK  ] Reached target Network.
         Starting Network Name Resolution...
         Starting Xinetd A Powerful Replacement For Inetd...
         Starting Permit User Sessions...
[  OK  ] Started Network Name Resolution.
[  OK  ] Started Xinetd A Powerful Replacement For Inetd.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Serial Getty on ttymxc0.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
[  OK  ] Reached target Multi-User System.
[  OK  ] Started Misa startup service.
[  OK  ] Reached target Graphical Interface.
         Starting Update UTMP about System Runlevel Changes...





***********************************************************************
Middlesex Industries S.A. - All rights reserved

initUpdtScript (v. 2.0.1):
***********************************************************************


The script is run as root !!

Enabling the core dumps


Setting ownership and privileges for /var, /work and /tmp
Start checking the system configuration:

Checking for already running application instances (TmuControlApp)

Checking for already running application instances (TmuDispatchController)

Checking for already running application instances (TmuHostInterface)
Checking the configuration dir (/work/data/config)
Check '/work/data/config': exists
Check '/work/data/log': exists
/work/data/log renamed log_bck_1
Check: '/work/data/log' created

initUpdtScript: configuring the eth0 interface
initUpdtScript: only for flash set up
FLASH board
ip addr flush dev eth0;
ip link set eth0 down;
ip link set eth0 up;
[    7.863614] fec 2188000.ethernet eth0: Freescale FEC PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=2188000.ethernet:07, irq=70)
[    7.878893] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
ip addr add 192.168.10.2/24 brd + dev eth0;

initUpdtScript: configuring the lo interface
ip addr flush dev lo;
ip link set lo down;
ip link set lo up;
ip addr add 127.0.0.1/8 dev lo;
RTNETLINK answers: File exists

initUpdtScript: loading the CAN driver
tmu_app_conf_custom.txt exists
ip link set can0 type can bitrate 500000
ip link set can0 up
[    7.974873] flexcan 2090000.flexcan can0: writing ctrl=0x03292005

Check for upgrades / backups in /var/www/uploads

'tmu_backup.tar.gz' not found, skipping upgrade
'tmu_upgrade.tar.gz' not found, skipping upgrade
chown: /work/TmuDispatchController: No such file or directory
chmod: /work/TmuDispatchController: No such file or directory
chown: /work/TmuHostInterface: No such file or directory
chmod: /work/TmuHostInterface: No such file or directory

KEEPALIVE settings - These settings are system-wide

keepalive_time= 10
keepalive_intvl= 2
keepalive_probes= 2

initUpdtScript: start the control application (TmuControlApp)
TMU Control Application (v. 3.8.43) started 

Apalis-iMX6-misa_Console-Image 2.7b5 20180330

apalis-imx6-misa login: [   11.311227] fec 2188000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   11.319044] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   11.921437] ci_hdrc ci_hdrc.0: Device No Response

Apalis-iMX6-misa_Console-Image 2.7b5 20180330

apalis-imx6-misa login: 
Apalis-iMX6-misa_Console-Image 2.7b5 20180330

apalis-imx6-misa login: 
Apalis-iMX6-misa_Console-Image 2.7b5 20180330

apalis-imx6-misa login: 
Apalis-iMX6-misa_Console-Image 2.7b5 20180330

apalis-imx6-misa login:

Here is clean shutdown for 2.8:

apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ sudo halt
Password: 
         Stopping Save/Res         Stopping Session c1 of user misa.
[  OK  ] Stopped Misa startup service.
[  OK  ] Stopped target Multi-User System.
         Stopping Avahi mDNS/DNS-SD Stack...
         Stopping D-Bus System Message Bus...
         Stopping Xinetd A Powerful Replacement For Inetd...
[  OK  ] Stopped target Login Prompts.
         Stopping Serial Getty on ttymxc0...
         Stopping Getty on tty1...
         Stopping Boa web server...
         Stopping NFS status monitor for NFSv2/3 locking....
         Stopping Telephony service...
         Stopping User Manager for UID 1000...
[  OK  ] Stopped target Timers.
[  OK  ] Stopped Daily Cleanup of Temporary Directories.
[  OK  ] Stopped target System Time Synchronized.
[  OK  ] Stopped target Sound Card.
[  OK  ] Stopped target Containers.
[  OK  ] Closed Load/Save RF Kill Switch Status /dev/rfkill Watch.
[  OK  ] Stopped Telephony service.
[  OK  ] Stopped Boa web server.
[  OK  ] Stopped D-Bus System Message Bus.
[  OK  ] Stopped Avahi mDNS/DNS-SD Stack.
[  OK  ] Stopped Xinetd A Powerful Replacement For Inetd.
[  OK  ] Stopped Serial Getty on ttymxc0.
[  OK  ] Stopped Getty on tty1.
[  OK  ] Stopped NFS status monitor for NFSv2/3 locking..
[  OK  ] Stopped User Manager for UID 1000.
[  OK  ] Stopped Save/Restore Sound Card State.
[  OK  ] Stopped Session c1 of user misa.
[  OK  ] Removed slice User Slice of misa.
         Stopping Login Service...
[  OK  ] Stopped target Host and Network Name Lookups.
         Stopping Network Name Resolution...
         Stopping RPC Bind Service...
[  OK  ] Removed slice system-getty.slice.
         Stopping Permit User Sessions...
[  OK  ] Removed slice system-serial\x2dgetty.slice.
[  OK  ] Stopped Login Service.
[  OK  ] Stopped RPC Bind Service.
[  OK  ] Stopped Network Name Resolution.
[  OK  ] Stopped Permit User Sessions.
[  OK  ] Stopped target Remote File Systems.
[  OK  ] Stopped target Network.
         Stopping Network Service...
[  OK  ] Stopped target Basic System.
[  OK  ] Stopped target Paths.
[  OK  ] Stopped Dispatch Password Requests to Console Directory Watch.
[  OK  ] Stopped Forward Password Requests to Wall Directory Watch.
[  OK  ] Stopped target Sockets.
[  OK  ] Closed Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Closed D-Bus System Message Bus Socket.
[  OK  ] Closed sshd.socket.
[  OK  ] Closed RPCbind Server Activation Socket.
[  OK  ] Stopped target Slices.
[  OK  ] Removed slice User and Session Slice.
[  OK  ] Stopped target System Initialization.
         Stopping Load/Save Screen Backlight…ightness of backlight:backlight...
         Stopping Network Time Synchronization...
         Stopping Update UTMP about System Boot/Shutdown...
         Stopping Load/Save Random Seed...
[  OK  ] Stopped Update is Completed.
[  OK  ] Stopped Rebuild Dynamic Linker Cache.
[  OK  ] Stopped Rebuild Journal Catalog.
[  OK  ] Stopped Rebuild Hardware Database.
[  OK  ] Stopped Network Time Synchronization.
[  OK  ] Stopped Network Service.
[  OK  ] Stopped Load/Save Screen Backlight Brightness of backlight:backlight.
[  OK  ] Stopped Load/Save Random Seed.
[  OK  ] Stopped Update UTMP about System Boot/Shutdown.
[  OK  ] Removed slice system-systemd\x2dbacklight.slice.
[  OK  ] Stopped Apply Kernel Variables.
[  OK  ] Stopped Load Kernel Modules.
[  OK  ] Stopped Create Volatile Files and Directories.
[  OK  ] Stopped target Local File Systems.
         Unmounting /run/user/1000...
         Unmounting /var/volatile...
         Unmounting Temporary Directory (/tmp)...
[  OK  ] Unmounted /run/user/1000.
[  OK  ] Unmounted /var/volatile.
[  OK  ] Unmounted Temporary Directory (/tmp).
[  OK  ] Stopped target Swap.
[  OK  ] Reached target Unmount All Filesystems.
[  OK  ] Stopped target Local File Systems (Pre).
[  OK  ] Stopped Create Static Device Nodes in /dev.
[  OK  ] Stopped Create System Users.
[  OK  ] Stopped Remount Root and Kernel File Systems.
[  OK  ] Reached target Shutdown.
[  101.967455] systemd-shutdow: 19 output lines suppressed due to ratelimiting
[  102.054711] systemd-shutdown[1]: Sending SIGTERM to remaining processes...
[  102.075997] systemd-journald[217]: Received SIGTERM from PID 1 (systemd-shutdow).
[  102.113940] systemd-shutdown[1]: Sending SIGKILL to remaining processes...
[  102.136785] systemd-shutdown[1]: Unmounting file systems.
[  102.143198] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  102.176637] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
[  102.184793] systemd-shutdown[1]: Remounting '/' read-only with options 'data=ordered'.
[  102.194227] EXT4-fs (mmcblk0p2): re-mounted. Opts: data=ordered
[  102.200265] systemd-shutdown[1]: All filesystems unmounted.
[  102.205946] systemd-shutdown[1]: Deactivating swaps.
[  102.211121] systemd-shutdown[1]: All swaps deactivated.
[  102.216463] systemd-shutdown[1]: Detaching loop devices.
[  102.225705] systemd-shutdown[1]: All loop devices detached.
[  102.257633] ci_hdrc ci_hdrc.1: remove, state 4
[  102.262175] usb usb2: USB disconnect, device number 1
[  102.267429] usb 2-1: USB disconnect, device number 2
[  102.273592] ci_hdrc ci_hdrc.1: USB bus 2 deregistered
[  102.282113] ci_hdrc ci_hdrc.0: remove, state 4
[  102.286671] usb usb1: USB disconnect, device number 1
[  102.292264] ci_hdrc ci_hdrc.0: USB bus 1 deregistered
[  102.352708] reboot: System halted

And restarted by power cycling:

U-Boot 2016.11-2.8.1+g30a1208 (Mar 18 2018 - 15:42:53 +0100)

CPU:   Freescale i.MX6D rev1.5 at 792 MHz
Reset cause: POR
I2C:   ready
DRAM:  512 MiB
PMIC:  device id: 0x10, revision id: 0x21, programmed
MMC:   FSL_SDHC: 0, FSL_SDHC: 1, FSL_SDHC: 2
auto-detected panel vga-rgb
Display: vga-rgb (640x480)
In:    serial
Out:   serial
Err:   serial
Model: Toradex Apalis iMX6 Dual 512MB V1.1B, Serial# 05095230
Net:   using PHY at 7
FEC [PRIME]
Hit any key to stop autoboot:  0 
Booting from internal eMMC chip...
reading imx6q-apalis-eval.dtb
53915 bytes read in 18 ms (2.9 MiB/s)
reading uImage
5363832 bytes read in 160 ms (32 MiB/s)
## Booting kernel from Legacy Image at 11000000 ...
   Image Name:   Linux-4.9.67-+g1db9f06
   Image Type:   ARM Linux Kernel Image (uncompressed)
   Data Size:    5363768 Bytes = 5.1 MiB
   Load Address: 10008000
   Entry Point:  10008000
   Verifying Checksum ... OK
## Flattened Device Tree blob at 12000000
   Booting using the fdt blob at 0x12000000
   Loading Kernel Image ... OK
   Using Device Tree in place at 12000000, end 1201029a

Starting kernel ...

[    0.000000] Booting Linux on physical CPU 0x0
[    0.000000] Linux version 4.9.67-+g1db9f06 (oe-user@oe-host) (gcc version 7.2.0 (GCC) ) #1 SMP Sun Mar 18 15:39:50 CET 2018
[    0.000000] CPU: ARMv7 Processor [412fc09a] revision 10 (ARMv7), cr=10c5387d
[    0.000000] CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
[    0.000000] OF: fdt:Machine model: Toradex Apalis iMX6Q/D Module on Apalis Evaluation Board
[    0.000000] Reserved memory: created CMA memory pool at 0x1c000000, size 320 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Memory policy: Data cache writealloc
[    0.000000] percpu: Embedded 14 pages/cpu @8bb13000 s25612 r8192 d23540 u57344
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 130048
[    0.000000] Kernel command line: enable_wait_mode=off vmalloc=400M ip=off root=/dev/mmcblk0p2 ro rootfstype=ext4 rootwait fec_mac=00:14:2d:4dM
[    0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[    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: 179124K/524288K available (8192K kernel code, 279K rwdata, 1956K rodata, 1024K init, 439K bss, 17484K reserved, 327680K c)
[    0.000000] Virtual kernel memory layout:
[    0.000000]     vector  : 0xffff0000 - 0xffff1000   (   4 kB)
[    0.000000]     fixmap  : 0xffc00000 - 0xfff00000   (3072 kB)
[    0.000000]     vmalloc : 0xa0800000 - 0xff800000   (1520 MB)
[    0.000000]     lowmem  : 0x80000000 - 0xa0000000   ( 512 MB)
[    0.000000]     pkmap   : 0x7fe00000 - 0x80000000   (   2 MB)
[    0.000000]     modules : 0x7f000000 - 0x7fe00000   (  14 MB)
[    0.000000]       .text : 0x80008000 - 0x80900000   (9184 kB)
[    0.000000]       .init : 0x80b00000 - 0x80c00000   (1024 kB)
[    0.000000]       .data : 0x80c00000 - 0x80c45e60   ( 280 kB)
[    0.000000]        .bss : 0x80c45e60 - 0x80cb3d8c   ( 440 kB)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000]  Build-time adjustment of leaf fanout to 32.
[    0.000000]  RCU restricting CPUs from NR_CPUS=4 to nr_cpu_ids=2.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=32, nr_cpu_ids=2
[    0.000000] NR_IRQS:16 nr_irqs:16 16
[    0.000000] L2C-310 errata 752271 769419 enabled
[    0.000000] L2C-310 enabling early BRESP for Cortex-A9
[    0.000000] L2C-310 full line of zeros enabled for Cortex-A9
[    0.000000] L2C-310 ID prefetch enabled, offset 16 lines
[    0.000000] L2C-310 dynamic clock gating enabled, standby mode enabled
[    0.000000] L2C-310 cache controller enabled, 16 ways, 1024 kB
[    0.000000] L2C-310: CACHE_ID 0x410000c7, AUX_CTRL 0x76470001
[    0.000000] Switching to timer-based delay loop, resolution 333ns
[    0.000006] sched_clock: 32 bits at 3000kHz, resolution 333ns, wraps every 715827882841ns
[    0.000024] clocksource: mxc_timer1: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 637086815595 ns
[    0.001496] Console: colour dummy device 80x30
[    0.002139] console [tty1] enabled
[    0.002167] Calibrating delay loop (skipped), value calculated using timer frequency.. 6.00 BogoMIPS (lpj=30000)
[    0.002209] pid_max: default: 32768 minimum: 301
[    0.002313] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.002340] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[    0.002932] CPU: Testing write buffer coherency: ok
[    0.003220] CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
[    0.003296] Setting up static identity map for 0x10100000 - 0x10100058
[    0.005064] CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
[    0.005164] Brought up 2 CPUs
[    0.005213] SMP: Total of 2 processors activated (12.00 BogoMIPS).
[    0.005237] CPU: All CPU(s) started in SVC mode.
[    0.005975] devtmpfs: initialized
[    0.019430] VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
[    0.019766] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
[    0.019819] futex hash table entries: 512 (order: 3, 32768 bytes)
[    0.036512] pinctrl core: initialized pinctrl subsystem
[    0.037585] NET: Registered protocol family 16
[    0.044848] DMA: preallocated 256 KiB pool for atomic coherent allocations
[    0.069581] cpuidle: using governor menu
[    0.069890] CPU identified as i.MX6Q, silicon rev 1.5
[    0.094762] hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
[    0.094811] hw-breakpoint: maximum watchpoint size is 4 bytes.
[    0.095776] imx6q-pinctrl 20e0000.iomuxc: initialized IMX pinctrl driver
[    0.097250] imx-gpc 20dc000.gpc: no fsl,ldo-bypass found!
[    0.097399] imx-gpc 20dc000.gpc: Registered imx-gpc
[    0.118633] usb_host_vbus: supplied by usb_host_vbus_hub
[    0.119116] vgaarb: loaded
[    0.119873] SCSI subsystem initialized
[    0.120326] usbcore: registered new interface driver usbfs
[    0.120412] usbcore: registered new interface driver hub
[    0.120504] usbcore: registered new device driver usb
[    0.121151] i2c-gpio i2c@0: using pins 80 (SDA) and 62 (SCL)
[    0.122126] i2c i2c-1: IMX I2C adapter registered
[    0.122164] i2c i2c-1: can't use DMA, using PIO instead.
[    0.130238] stmpe-i2c 2-0041: stmpe811 detected, chip id: 0x811
[    0.220519] i2c i2c-2: IMX I2C adapter registered
[    0.220558] i2c i2c-2: can't use DMA, using PIO instead.
[    0.221261] i2c i2c-3: IMX I2C adapter registered
[    0.221297] i2c i2c-3: can't use DMA, using PIO instead.
[    0.221454] Linux video capture interface: v2.00
[    0.221519] pps_core: LinuxPPS API ver. 1 registered
[    0.221544] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.221594] PTP clock support registered
[    0.249640] imx-ipuv3 2400000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.279633] imx-ipuv3 2800000.ipu: IPU DMFC NORMAL mode: 1(0~1), 5B(4,5), 5F(6,7)
[    0.280558] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 driver probed ipu0 csi1
[    0.280603] mxc_mipi_csi2 21dc000.mipi_csi: i.MX MIPI CSI2 dphy version is 0x3130302a
[    0.280716] MIPI CSI2 driver module loaded
[    0.280910] Advanced Linux Sound Architecture Driver Initialized.
[    0.282664] clocksource: Switched to clocksource mxc_timer1
[    0.282786] VFS: Disk quotas dquot_6.6.0
[    0.282860] VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.292955] NET: Registered protocol family 2
[    0.293537] TCP established hash table entries: 4096 (order: 2, 16384 bytes)
[    0.293607] TCP bind hash table entries: 4096 (order: 3, 32768 bytes)
[    0.293689] TCP: Hash tables configured (established 4096 bind 4096)
[    0.293756] UDP hash table entries: 256 (order: 1, 8192 bytes)
[    0.293798] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[    0.293946] NET: Registered protocol family 1
[    0.294334] RPC: Registered named UNIX socket transport module.
[    0.294364] RPC: Registered udp transport module.
[    0.294386] RPC: Registered tcp transport module.
[    0.294408] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.295151] hw perfevents: enabled with armv7_cortex_a9 PMU driver, 7 counters available
[    0.296694] Bus freq driver module loaded
[    0.298353] workingset: timestamp_bits=30 max_order=17 bucket_order=0
[    0.304678] NFS: Registering the id_resolver key type
[    0.304726] Key type id_resolver registered
[    0.304749] Key type id_legacy registered
[    0.304781] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    0.305316] ntfs: driver 2.1.32 [Flags: R/W].
[    0.305640] fuse init (API version 7.26)
[    0.311568] io scheduler noop registered
[    0.311602] io scheduler deadline registered
[    0.311774] io scheduler cfq registered (default)
[    0.314061] OF: PCI: host bridge /soc/pcie@0x01000000 ranges:
[    0.314100] OF: PCI:   No bus range found for /soc/pcie@0x01000000, using [bus 00-ff]
[    0.314152] OF: PCI:    IO 0x01f80000..0x01f8ffff -> 0x00000000
[    0.314184] OF: PCI:   MEM 0x01000000..0x01efffff -> 0x01000000
[    0.563807] imx6q-pcie 1ffc000.pcie: phy link never came up
[    0.563844] imx6q-pcie 1ffc000.pcie: Link never came up
[    0.566928] imx6q-pcie 1ffc000.pcie: failed to initialize host
[    0.567025] imx6q-pcie: probe of 1ffc000.pcie failed with error -110
[    0.568041] backlight supply power not found, using dummy regulator
[    0.570924] 20e0000.hdmi_video supply HDMI not found, using dummy regulator
[    0.572926] mxc_hdmi 20e0000.hdmi_video: Detected HDMI controller 0x13:0xa:0xa0:0xc1
[    0.572987] fbcvt: 1920x1080@60: CVT Name - 2.073M9
[    0.573115] mxc_sdc_fb fb@0: registered mxc display driver hdmi
[    0.587758] mxc_sdc_fb fb@0: 1920x1080 h_sync,r,l: 44,88,148  v_sync,l,u: 5,4,36 pixclock=148500000 Hz
[    0.612740] imx-ipuv3 2400000.ipu: IPU DMFC DP HIGH RESOLUTION: 1(0,1), 5B(2~5), 5F(6,7)
[    0.646623] mxc_sdc_fb fb@0: 1920x1080 h_sync,r,l: 44,88,148  v_sync,l,u: 5,4,36 pixclock=148500000 Hz
[    0.694436] Console: switching to colour frame buffer device 240x67
[    0.729266] mxc_sdc_fb fb@1: mxcfb1 is turned off!
[    0.729417] mxc_sdc_fb fb@2: mxcfb2 is turned off!
[    0.729560] mxc_sdc_fb fb@3: mxcfb3 is turned off!
[    0.731387] imx-sdma 20ec000.sdma: no iram assigned, using external mem
[    0.732035] imx-sdma 20ec000.sdma: loaded firmware 3.3
[    0.736640] pfuze100-regulator 2-0008: Full layer: 2, Metal layer: 1
[    0.737472] pfuze100-regulator 2-0008: FAB: 0, FIN: 0
[    0.737572] pfuze100-regulator 2-0008: pfuze100 found.
[    0.754871] 2020000.serial: ttymxc0 at MMIO 0x2020000 (irq = 30, base_baud = 5000000) is a IMX
[    1.642912] console [ttymxc0] enabled
[    1.647540] 21e8000.serial: ttymxc1 at MMIO 0x21e8000 (irq = 80, base_baud = 5000000) is a IMX
[    1.656877] 21f0000.serial: ttymxc3 at MMIO 0x21f0000 (irq = 81, base_baud = 5000000) is a IMX
[    1.666229] 21f4000.serial: ttymxc4 at MMIO 0x21f4000 (irq = 82, base_baud = 5000000) is a IMX
[    1.675899] [drm] Initialized
[    1.679311] [drm] Initialized vivante 1.0.0 20120216 on minor 0
[    1.694668] brd: module loaded
[    1.703363] loop: module loaded
[    1.707746] ahci-imx 2200000.sata: fsl,transmit-level-mV not specified, using 00000024
[    1.715842] ahci-imx 2200000.sata: fsl,transmit-boost-mdB not specified, using 00000480
[    1.724017] ahci-imx 2200000.sata: fsl,transmit-atten-16ths not specified, using 00002000
[    1.732340] ahci-imx 2200000.sata: fsl,receive-eq-mdB not specified, using 05000000
[    1.743243] ahci-imx 2200000.sata: SSS flag set, parallel bus scan disabled
[    1.750358] ahci-imx 2200000.sata: AHCI 0001.0300 32 slots 1 ports 3 Gbps 0x1 impl platform mode
[    1.759332] ahci-imx 2200000.sata: flags: ncq sntf stag pm led clo only pmp pio slum part ccc apst 
[    1.769894] scsi host0: ahci-imx
[    1.773519] ata1: SATA max UDMA/133 mmio [mem 0x02200000-0x02203fff] port 0x100 irq 89
[    1.783884] spi_imx 2008000.ecspi: probed
[    1.789020] spi_imx 200c000.ecspi: probed
[    1.793862] libphy: Fixed MDIO Bus: probed
[    1.798409] vcan: Virtual CAN interface driver
[    1.802961] CAN device driver interface
[    1.811952] 2090000.flexcan supply xceiver not found, using dummy regulator
[    1.824595] flexcan 2090000.flexcan: device registered (reg_base=a0b2c000, irq=39)
[    1.837471] 2094000.flexcan supply xceiver not found, using dummy regulator
[    1.850303] flexcan 2094000.flexcan: device registered (reg_base=a0b34000, irq=40)
[    1.864043] 2188000.ethernet supply phy not found, using dummy regulator
[    1.887359] pps pps0: new PPS source ptp0
[    1.897794] libphy: fec_enet_mii_bus: probed
[    1.908703] fec 2188000.ethernet eth0: registered PHC device 0
[    1.920054] usbcore: registered new interface driver asix
[    1.930651] usbcore: registered new interface driver ax88179_178a
[    1.941893] usbcore: registered new interface driver cdc_ether
[    1.952818] usbcore: registered new interface driver net1080
[    1.963476] usbcore: registered new interface driver cdc_subset
[    1.974342] usbcore: registered new interface driver zaurus
[    1.984885] usbcore: registered new interface driver cdc_ncm
[    1.995395] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    2.006836] ehci-pci: EHCI PCI platform driver
[    2.016248] ehci-mxc: Freescale On-Chip EHCI Host driver
[    2.026699] usbcore: registered new interface driver usb-storage
[    2.037749] usbcore: registered new interface driver usbserial
[    2.048470] usbcore: registered new interface driver usbserial_generic
[    2.059831] usbserial: USB Serial support registered for generic
[    2.070638] usbcore: registered new interface driver ftdi_sio
[    2.081219] usbserial: USB Serial support registered for FTDI USB Serial Device
[    2.093410] usbcore: registered new interface driver pl2303
[    2.103815] usbserial: USB Serial support registered for pl2303
[    2.115734] 2184800.usbmisc supply vbus-wakeup not found, using dummy regulator
[    2.119100] ata1: SATA link down (SStatus 0 SControl 300)
[    2.119140] ahci-imx 2200000.sata: no device found, disabling link.
[    2.119146] ahci-imx 2200000.sata: pass ahci_imx..hotplug=1 to enable hotplug
[    2.165683] ci_hdrc ci_hdrc.0: EHCI Host Controller
[    2.175241] ci_hdrc ci_hdrc.0: new USB bus registered, assigned bus number 1
[    2.212699] ci_hdrc ci_hdrc.0: USB 2.0 started, EHCI 1.00
[    2.223665] hub 1-0:1.0: USB hub found
[    2.232137] hub 1-0:1.0: 1 port detected
[    2.246851] ci_hdrc ci_hdrc.1: EHCI Host Controller
[    2.256445] ci_hdrc ci_hdrc.1: new USB bus registered, assigned bus number 2
[    2.292698] ci_hdrc ci_hdrc.1: USB 2.0 started, EHCI 1.00
[    2.303588] hub 2-0:1.0: USB hub found
[    2.312030] hub 2-0:1.0: 1 port detected
[    2.321498] mousedev: PS/2 mouse device common for all mice
[    2.341927] input: stmpe-ts as /devices/soc0/soc/2100000.aips-bus/21a4000.i2c/i2c-2/2-0041/stmpe-ts/input/input0
[    2.360849] rtc-ds1307 1-0068: oscillator failed, set time!
[    2.374143] rtc-ds1307 1-0068: rtc core: registered m41t0 as rtc0
[    2.385810] snvs_rtc 20cc000.snvs:snvs-rtc-lp: rtc core: registered 20cc000.snvs:snvs-r as rtc1
[    2.399713] i2c /dev entries driver
[    2.409439] mxc_v4l2_output v4l2_out: V4L2 device registered as video16
[    2.421323] mxc_v4l2_output v4l2_out: V4L2 device registered as video17
[    2.434222] imx2-wdt 20bc000.wdog: timeout 60 sec (nowayout=0)
[    2.445705] sdhci: Secure Digital Host Controller Interface driver
[    2.456767] sdhci: Copyright(c) Pierre Ossman
[    2.465962] sdhci-pltfm: SDHCI platform and OF driver helper
[    2.477579] sdhci-esdhc-imx 2190000.usdhc: could not get ultra high speed state, work on normal mode
[    2.491710] sdhci-esdhc-imx 2190000.usdhc: Got CD GPIO
[    2.562819] mmc1: SDHCI controller on 2190000.usdhc [2190000.usdhc] using ADMA
[    2.577641] sdhci-esdhc-imx 2194000.usdhc: could not get ultra high speed state, work on normal mode
[    2.591769] sdhci-esdhc-imx 2194000.usdhc: Got CD GPIO
[    2.662809] mmc2: SDHCI controller on 2194000.usdhc [2194000.usdhc] using ADMA
[    2.677644] sdhci-esdhc-imx 2198000.usdhc: could not get ultra high speed state, work on normal mode
[    2.692042] usb 2-1: new high-speed USB device number 2 using ci_hdrc
[    2.762695] mmc0: SDHCI controller on 2198000.usdhc [2198000.usdhc] using ADMA
[    2.787668] mxc_vpu 2040000.vpu_fsl: VPU initialized
[    2.802438] mxc_vdoa 21e4000.vdoa: i.MX Video Data Order Adapter(VDOA) driver probed
[    2.824775] caam 2100000.caam: ERA source: CAAMVID.
[    2.834849] caam 2100000.caam: AXI pipeline throttling enabled.
[    2.853132] caam 2100000.caam: Entropy delay = 3200
[    2.863229] caam 2100000.caam: Instantiated RNG4 SH0
[    2.898443] hub 2-1:1.0: USB hub found
[    2.908352] random: fast init done
[    2.916779] hub 2-1:1.0: 4 ports detected
[    2.923992] caam 2100000.caam: Instantiated RNG4 SH1
[    2.924002] caam 2100000.caam: device ID = 0x0a16010000000000 (Era 4)
[    2.924009] caam 2100000.caam: job rings = 2, qi = 0
[    2.931851] caam algorithms registered in /proc/crypto
[    2.947856] caam_jr 2101000.jr0: registering rng-caam
[    2.948718] snvs-secvio 20cc000.caam-snvs: can't get snvs clock
[    2.948766] snvs-secvio 20cc000.caam-snvs: violation handlers armed - non-secure state
[    2.948987] hidraw: raw HID events driver (C) Jiri Kosina
[    2.949135] usbcore: registered new interface driver usbhid
[    2.949137] usbhid: USB HID core driver
[    3.040657] stmpe-adc stmpe-adc: Initialized
[    3.049319] mmc0: new DDR MMC card at address 0001
[    3.060654] mmcblk0: mmc0:0001 Q2J54A 3.64 GiB 
[    3.070710] mmcblk0boot0: mmc0:0001 Q2J54A partition 1 2.00 MiB
[    3.082102] mmcblk0boot1: mmc0:0001 Q2J54A partition 2 2.00 MiB
[    3.093420] sgtl5000 2-000a: sgtl5000 revision 0x11
[    3.094319]  mmcblk0: p1 p2
[    3.140827] fsl-ssi-dai 2028000.ssi: No cache defaults, reading back from HW
[    3.283556] imx-sgtl5000 sound: sgtl5000 <-> 2028000.ssi mapping ok
[    3.297259] imx-spdif sound-spdif: snd-soc-dummy-dai <-> 2004000.spdif mapping ok
[    3.312775] imx-audio-hdmi sound-hdmi: hdmi-hifi.0 <-> soc:hdmi_audio@00120000 mapping ok
[    3.327587] NET: Registered protocol family 10
[    3.338106] NET: Registered protocol family 17
[    3.347692] can: controller area network core (rev 20120528 abi 9)
[    3.359149] NET: Registered protocol family 29
[    3.368714] can: raw protocol (rev 20120528)
[    3.378104] can: broadcast manager protocol (rev 20161123 t)
[    3.388909] can: netlink gateway (rev 20130117) max_hops=1
[    3.399857] Key type dns_resolver registered
[    3.410596] mmc2: Switching to 3.3V signalling voltage failed
[    3.423163] cpu cpu0: Registered imx6q-cpufreq
[    3.432864] Registering SWP/SWPB emulation handler
[    3.457322] imx_thermal 2000000.aips-bus:tempmon: Extended Commercial CPU temperature grade - max:105C critical:100C passive:95C
[    3.474170] input: gpio-keys as /devices/soc0/gpio-keys/input/input1
[    3.486421] rtc-ds1307 1-0068: hctosys: unable to read the hardware clock
[    3.500066] ALSA device list:
[    3.507213]   #0: imx6q-apalis-sgtl5000
[    3.515263]   #1: imx-spdif
[    3.522067]   #2: imx-hdmi-soc
[    3.570025] EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
[    3.582368] VFS: Mounted root (ext4 filesystem) readonly on device 179:2.
[    3.593720] devtmpfs: mounted
[    3.601611] Freeing unused kernel memory: 1024K
[    3.757377] systemd[1]: System time before build time, advancing clock.
[    3.801867] systemd[1]: systemd 234 running in system mode. (+PAM -AUDIT -SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP -LIBCRYPTSETUP -GCRYP)
[    3.827503] systemd[1]: Detected architecture arm.

Welcome to The Ångström Distribution v2017.12!

[    3.863080] systemd[1]: Set hostname to <apalis-imx6-misa>.
[    4.097362] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[    4.142974] systemd[1]: Listening on udev Control Socket.
[  OK  ] Listening on udev Control Socket.
[    4.182965] systemd[1]: Listening on Journal Socket.
[  OK  ] Listening on Journal Socket.
[    4.222992] systemd[1]: Listening on udev Kernel Socket.
[  OK  ] Listening on udev Kernel Socket.
[    4.263018] systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
[  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[    4.302834] systemd[1]: Reached target Remote File Systems.
[  OK  ] Reached target Remote File Systems.
[    4.358803] systemd[1]: Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Journal Socket (/dev/log).
[  OK  ] Listening on Network Service Netlink Socket.
[  OK  ] Started Forward Password Requests to Wall Directory Watch.
[  OK  ] Reached target Paths.
[  OK  ] Created slice System Slice.
[  OK  ] Created slice system-serial\x2dgetty.slice.
         Starting Load Kernel Modules...
         Starting Export GPIOs to user space...
         Mounting Kernel Debug File System...
         Starting Journal Service...
         Starting Create list of required st…ce nodes for the current kernel...
         Starting File System Check on Root Device...
[  OK  ] Reached target Swap.
         Mounting Temporary Directory (/tmp)...
[  OK  ] Created slice User and Session Slice.
[  OK  ] Reached target Slices.
[  OK  ] Created slice system-getty.slice.
[  OK  ] Mounted Kernel Debug File System.
[  OK  ] Mounted Temporary Directory (/tmp).
[  OK  ] Started Load Kernel Modules.
[  OK  ] Started Export GPIOs to user space.
[  OK  ] Started Create list of required sta…vice nodes for the current kernel.
         Mounting FUSE Control File System...
         Starting Apply Kernel Variables...
         Mounting Kernel Configuration File System...
[  OK  ] Mounted Kernel Configuration File System.
[  OK  ] Mounted FUSE Control File System.
[  OK  ] Started Journal Service.
[  OK  ] Started File System Check on Root Device.
[  OK  ] Started Apply Kernel Variables.
         Starting Remount Root and Kernel File Systems...
[    5.144136] EXT4-fs (mmcblk0p2): re-mounted. Opts: (null)
[  OK  ] Started Remount Root and Kernel File Systems.
         Starting Create System Users...
         Starting Rebuild Hardware Database...
         Starting Flush Journal to Persistent Storage...
[  OK  ] Started Create System Users.
[    5.305746] systemd-journald[213]: Received request to flush runtime journal from PID 1
         Starting Create Static Device Nodes in /dev...
[    5.330950] systemd-journald[213]: File /var/log/journal/f6a76da00d724b30a04207da6eb33c09/system.journal corrupted or uncleanly shut down, re.
[  OK  ] Started Create Static Device Nodes in /dev.
         Starting udev Kernel Device Manager...
[  OK  ] Reached target Local File Systems (Pre).
         Mounting /var/volatile...
[  OK  ] Mounted /var/volatile.
[  OK  ] Started udev Kernel Device Manager.
[  OK  ] Started Flush Journal to Persistent Storage.
         Starting Load/Save Random Seed...
[  OK  ] Reached target Local File Systems.
         Starting Create Volatile Files and Directories...
         Starting Rebuild Dynamic Linker Cache...
         Starting Rebuild Journal Catalog...
[  OK  ] Started Load/Save Random Seed.
[  OK  ] Started Create Volatile Files and Directories.
[  OK  ] Started Rebuild Dynamic Linker Cache.
[  OK  ] Started Rebuild Journal Catalog.
         Starting Update UTMP about System Boot/Shutdown...
         Starting Network Time Synchronization...
[  OK  ] Started Update UTMP about System Boot/Shutdown.
[  OK  ] Started Network Time Synchronization.
[  OK  ] Reached target System Time Synchronized.
[  OK  ] Started Rebuild Hardware Database.
         Starting Update is Completed...
         Starting udev Coldplug all Devices...
[  OK  ] Started Update is Completed.
[  OK  ] Started udev Coldplug all Devices.
[  OK  ] Reached target System Initialization.
[  OK  ] Started Daily Cleanup of Temporary Directories.
[  OK  ] Reached target Timers.
         Starting sshd.socket.
[  OK  ] Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
[  OK  ] Listening on D-Bus System Message Bus Socket.
[  OK  ] Listening on RPCbind Server Activation Socket.
[  OK  ] Listening on sshd.socket.
[  OK  ] Found device /dev/ttymxc0.
[    7.719102] max9526 3-0020: Device seems not to be a MAX9526
[    7.751399] adv7280 3-0021: chip found @ 0x42 (21a8000.i2c)
[  OK  ] Created slice system-systemd\x2dbacklight.slice.
[    7.781262] adv7280 3-0021: no sensor pwdn pin available
         Starting Load/Save Screen Backlight…ightness of backlight:backlight...
[  OK  ] Reached target Sockets.
[    7.853183] adv7280 3-0021: adv7280_probe:Analog Device adv7280 not detected -6!
[  OK  ] Reached target Basic System.
[    7.901556] ov564x_mipi 3-003c: found pwm2, period=0
         Starting Avahi mDNS/DNS-SD Stack...
         Starting Telephony service...
[  OK  ] Started D-Bus System Message Bus.
[    8.193079] ov5640_read_reg:write reg error:reg=300a
[    8.203173] camera ov5640_mipi is not found
[  OK  ] Started Avahi mDNS/DNS-SD Stack.
[    8.309146] compat: loading out-of-tree module taints kernel.
[  OK  ] Started Telephony service.
[  OK  ] Reached target Containers.
[    8.363693] Loading modules backported from Linux version v4.14-0-gbebc608
[    8.375729] Backport generated by backports.git v4.14-rc2-1-34-g1d8cc15
         Starting Boa web server...
         Starting Save/Restore Sound Card State...
         Starting RPC Bind Service...
         Starting Load default USB gadget schema g1.schema...
[    8.518535] Bluetooth: Core ver 2.22

         Starting Login Service...
[  OK  ] Started Load/Save Screen Backlight Brightness of backlight:backlight.
[  OK  ] Started Boa web server.
[    8.624516] NET: Registered protocol family 31
[    8.634030] Bluetooth: HCI device and connection manager initialized
[  OK  ] Started RPC Bind Service.
[    8.703901] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[    8.717596] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[    8.727414] ERROR: v4l2 capture: slave not found! V4L2_CID_HUE
[    8.744041] Bluetooth: HCI socket layer initialized
[FAILED] Failed to st[    8.755680] Bluetooth: L2CAP socket layer initialized
art Load default USB gadget schem[    8.766874] Bluetooth: SCO socket layer initialized
a g1.schema.
See 'systemctl status usbg.service' for details.
[  OK  ] Started Save/Restore Sound Card State.
[  OK  ] Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
         Starting Network Service...
[  OK  ] Reached target Sound Card.
[  OK  ] Started Network Service.
[  OK  ] Started Login Service.
[  OK  ] Reached target Network.
         Starting Network Name Resolution...
         Starting Xinetd A Powerful Replacement For Inetd...
         Starting Permit User Sessions...
[  OK  ] Started Xinetd A Powerful Replacement For Inetd.
[  OK  ] Started Permit User Sessions.
[  OK  ] Started Serial Getty on ttymxc0.
[  OK  ] Started Getty on tty1.
[  OK  ] Reached target Login Prompts.
[  OK  ] Started Network Name Resolution.
[  OK  ] Reached target Host and Network Name Lookups.
[  OK  ] Started NFS status monitor for NFSv2/3 locking..
[  OK  ] Reached target Multi-User System.
[  OK  ] Reached target Graphical Interface.
[  OK  ] Started Misa startup service.





***********************************************************************
Middlesex Industries S.A. - All rights reserved

initUpdtScript (v. 2.0.1):
***********************************************************************


         Starting Update UTMP about System Runlevel Changes...
The script is run as root !!

Enabling the core dumps


Setting ownership and privileges for /var, /work and /tmp
Start checking the system configuration:

Checking for already running application instances (TmuControlApp)

Checking for already running application instances (TmuDispatchController)

Checking for already running application instances (TmuHostInterface)
Checking the configuration dir (/work/data/config)
Check '/work/data/config': exists
Check '/work/data/log': exists
/work/data/log renamed log_bck_1
Check: '/work/data/log' created

initUpdtScript: configuring the eth0 interface
initUpdtScript: only for flash set up
FLASH board
ip addr flush dev eth0;
ip link set eth0 down;
ip link set eth0 up;
[   10.125017] Micrel KSZ9031 Gigabit PHY 2188000.ethernet-1:07: attached PHY driver [Micrel KSZ9031 Gigabit PHY] (mii_bus:phy_addr=2188000.ethe)
[   10.145716] IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
ip addr add 192.168.10.2/24 brd + dev eth0;

initUpdtScript: configuring the lo interface
ip addr flush dev lo;
ip link set lo down;
ip link set lo up;
ip addr add 127.0.0.1/8 dev lo;
RTNETLINK answers: File exists

initUpdtScript: loading the CAN driver
ip link set can0 type can bitrate 500000
ip link set can0 up

Check for upgrades / backups in /var/www/uploads

'tmu_backup.tar.gz' not found, skipping upgrade
'tmu_upgrade.tar.gz' not found, skipping upgrade
chown: /work/TmuDispatchController: No such file or directory
chmod: /work/TmuDispatchController: No such file or directory
chown: /work/TmuHostInterface: No such file or directory
chmod: /work/TmuHostInterface: No such file or directory

KEEPALIVE settings - These settings are system-wide

keepalive_time= 10
keepalive_intvl= 2
keepalive_probes= 2

initUpdtScript: start the control application (TmuControlApp)
TMU Control Application (v. 3.8.43) started 
ExecuteStepE failed Unable to complete Start Procedure! Exiting application...

Apalis-iMX6-misa_Console-Image 2.8b1 20180329

apalis-imx6-misa login: [   13.827471] fec 2188000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   13.835494] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready

Apalis-iMX6-misa_Console-Image 2.8b1 20180329

apalis-imx6-misa login: misa
Password: 
Last login: Sun Mar 18 14:08:59 UTC 2018 on ttymxc0
[   25.006078] systemd-journald[213]: File /var/log/journal/f6a76da00d724b30a04207da6eb33c09/user-1000.journal corrupted or uncleanly shut down,.
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$ 
apalis-imx6-misa:~$
10

In general, the journal files are written by the systemd-journald service and contain log information. Their corruption should not be an issue in general.

However, if those files just corrupt without power cuts, it might be concerning…

Testing with the 2.8b1.64 build of the console-tdx-image (e.g. available via Toradex Easy Installer from the CI feeds) seems not to show the issue even after multiple reboots and halts.

Do you see this on multiple modules? How many reboots/poweroff are required until corruption occur?

11

Hello Stefan,

I have just 2 Apalis iMX6 modules: the one from the above console output is used as development, the other module is currently used as a continuous test for the new custom carrier board (with 2.7.3).

So far I have installed the 2.8.1 only on the development board, where I see the problem. The strange thing is that right after flashing the 2.8.1, the very first time it boots, I don’t think to have seen the journal corruption. Then I reboot the board with ‘sudo reboot’, and during all the following booting I see the problem. It needs just 1 reboot to show up.

Both my custom Image built with 2.8.1 and the 2.8.1 image built with ‘bitbake console-tdx-image’ show the same problem. I could try the console-tdx-image from Toradex Easy Installer to confirm;
Is there a way to flash it from U-boot via tftp ? Or should I use USB OTG ?

And one last thing, how can I get console-tdx-image from CI feeds inside Toradex Easy Installer ?

Thank you,

Nicola

12

Hello,

I loaded into memory the Toradex Easy Installer using the recovery procedure, and I flashed the same exact image that you used (from the feeds, console 2.8b1.64 from december 2017), then restarted the board and just to be sure I reset the U-boot environment variables.
Once I booted the Kernel, I could see the same problem of the journal being corrupted.

So we can exclude that the issue is related to my custom Image or to the console-txd-image generated with bitbake.

I start to think that the problem might be with my Apalis iMX6. But if it is the case, why only on the 2.8.1 and not the 2.7.5 ?

Can you please tell me what to do next ?

Thanks.
Nicola

13

Hello,

a further update.

Both the Apalis iMX6 boards that I use have the same problem of corrupted journal with 2.8.1. They behave in the exact same way.

I don’t believe it is a coincidence…

14

It seems that this is related to system time. You can see that restoring time from the RTC failed during boot:

[    1.910078] rtc-ds1307 1-0068: oscillator failed, set time!
[    1.920631] rtc-ds1307 1-0068: rtc core: registered m41t0 as rtc0

When the RTC was not able to keep system time, the system resets to some older date (date at build time). However, the journal on disk has a newer timestamp which makes systemd-journald refusing to load the journal, leading to the messages you are seeing:

[    5.464335] systemd-journald[215]: Received request to flush runtime journal from PID 1
[    5.493267] systemd-journald[215]: File /var/log/journal/2253ac6e062c467ca716d4ce5371db38/system.journal corrupted or uncleanly shut down, renaming and replacing.

This behavior has been introduced with systemd v232 (see journal: refuse opening journal files from the future for writing), and our 2.7 BSP series used systemd v230. That is why you only see it since our 2.8 BSP release which uses systemd v234.

In this case, the message is not really concerning. Systemd creates a new journal file and should continue to log as usual into a new file.

15

Thank you very much for the explanation.

Now everything is clear.