Serial over lan

General support questions
diosim
Posts: 10
Joined: 2017/06/02 09:32:25

Serial over lan

Postby diosim » 2017/07/12 08:48:51

Morning Everyone,

I'm currently testing serial over lan on centos 7.3.

The boot process is really slow, and stuck at some point, just to compare without serial over lan from the grub, boot process is less than 1min.

Do we need special options in the grub? What could be causing this issue?

Here my grub:

Code: Select all

GRUB_TIMEOUT=5
GRUB_DISTRIBUTOR="$(sed 's, release .*$,,g' /etc/system-release)"
GRUB_DEFAULT=saved
GRUB_DISABLE_SUBMENU=true
GRUB_TERMINAL_OUTPUT="console"
GRUB_TERMINAL="console serial"
GRUB_SERIAL_COMMAND="serial --speed=115200 --port=0x30e0 --unit=0 --word=8 --parity=no --stop=1"
GRUB_CMDLINE_LINUX="console=tty0 console=ttyS1,115200,rhgb"
GRUB_DISABLE_RECOVERY="true"


Here the boot process log, stuck at this point. It takes at least 10min until this process.

Code: Select all

[ 1022.817443] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)

Code: Select all

connected now, use ^] to escape
[   31.482827] systemd[1]: Set hostname to <xxxxxx>.
[[   61.515975] systemd[1]: Reached target Timers.
[   61.517772] systemd[1]: Starting Timers.
[[   91.521304] systemd[1]: Created slice Root Slice.
[   91.523021] systemd[1]: Starting Root Slice.
[[  121.526528] systemd[1]: Reached target Local File Systems.
[  121.528690] systemd[1]: Starting Local File Systems.
[[  151.532780] systemd[1]: Listening on Journal Socket.
[  151.534499] systemd[1]: Starting Journal Socket.
[[  181.538029] systemd[1]: Reached target Swap.
[  181.539472] systemd[1]: Starting Swap.
[[  211.542301] systemd[1]: Listening on udev Control Socket.
[  211.543941] systemd[1]: Starting udev Control Socket.
[[  241.547526] systemd[1]: Created slice System Slice.
[  241.549132] systemd[1]: Starting System Slice.
[[  271.552779] systemd[1]: Reached target Slices.
[  271.554287] systemd[1]: Starting Slices.
[  271.556102] systemd[1]: Starting Create list of required static device nodes for the current kernel...
         [  301.561851] systemd[1]: Starting Apply Kernel Variables...
         [  331.566516] systemd[1]: Starting Journal Service...
         [  361.569900] systemd[1]: Starting Setup Virtual Console...
         [[  421.576017] systemd[1]: Listening on udev Kernel Socket.
[  421.577943] systemd[1]: Starting udev Kernel Socket.
[[  451.581278] systemd[1]: Reached target Sockets.
[  451.582777] systemd[1]: Starting Sockets.
[  451.584526] systemd[1]: Starting dracut cmdline hook...
         [[  511.590788] systemd[1]: Started Journal Service.
[[[[                  [[         [         [[  841.639200] FUJITSU Extended Socket Network Device Driver - version 1.1 - Copyright (c) 2015 FUJITSU LIMITED
[  841.648505] sdhci: Secure Digital Host Controller Interface driver
[  841.650679] sdhci: Copyright(c) Pierre Ossman
[  841.656598] pps_core: LinuxPPS API ver. 1 registered
[  841.659168] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[  841.663597] PTP clock support registered
[  841.665105] [drm] Initialized drm 1.1.0 20060810
[  841.671372] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[  841.673297] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[  841.677352] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 3 ports 6 Gbps 0xb impl SATA mode
[  841.680263] ahci 0000:00:1f.2: flags: 64bit ncq pm led clo only pio slum part deso sadm sds apst
[  841.686342] scsi host0: ahci
[  841.687447] scsi host1: ahci
[  841.687559] scsi host2: ahci
[  841.687662] scsi host3: ahci
[  841.687730] ata1: SATA max UDMA/133 abar m2048@0xaa039000 port 0xaa039100 irq 43
[  841.687733] ata2: SATA max UDMA/133 abar m2048@0xaa039000 port 0xaa039180 irq 43
[  841.687735] ata3: DUMMY
[  841.687737] ata4: SATA max UDMA/133 abar m2048@0xaa039000 port 0xaa039280 irq 43
[  841.688020] e1000e 0000:00:19.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode
[  841.992390] ata2: SATA link down (SStatus 0 SControl 300)
[  841.994375] ata1: SATA link down (SStatus 0 SControl 300)
[  841.996148] ata4: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[  841.998808] ata4.00: supports DRM functions and may not be fully accessible
[  842.001483] ata4.00: ATA-10: Crucial_CT250MX200SSD4, MU03, max UDMA/133
[  842.003763] ata4.00: 488397168 sectors, multi 16: LBA48 NCQ (depth 31/32), AA
[  842.007300] ata4.00: supports DRM functions and may not be fully accessible
[  842.011361] ata4.00: configured for UDMA/133
[  842.012918] scsi 3:0:0:0: Direct-Access     ATA      Crucial_CT250MX2 MU03 PQ: 0 ANSI: 5
[  842.032728] ata4.00: Enabling discard_zeroes_data
[  842.034551] sd 3:0:0:0: [sda] 488397168 512-byte logical blocks: (250 GB/232 GiB)
[  842.037035] sd 3:0:0:0: [sda] 4096-byte physical blocks
[  842.038985] sd 3:0:0:0: [sda] Write Protect is off
[  842.040617] sd 3:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[  842.044122] ata4.00: Enabling discard_zeroes_data
[  842.046203]  sda: sda1 sda2 sda3 sda4 < sda5 sda6 >
[  842.048324] ata4.00: Enabling discard_zeroes_data
[  842.049928] sd 3:0:0:0: [sda] Attached SCSI disk
[  842.282754] e1000e 0000:00:19.0 (unregistered net_device): Reset blocked by ME
[  842.886225] e1000e 0000:00:19.0: PHY reset is blocked due to SOL/IDER session.
[  844.776374] e1000e 0000:00:19.0 eth0: registered PHC clock
[  844.778225] e1000e 0000:00:19.0 eth0: (PCI Express:2.5GT/s:Width x1) b8:ae:ed:7e:cb:b4
[  844.781105] e1000e 0000:00:19.0 eth0: Intel(R) PRO/1000 Network Connection
[  844.783400] e1000e 0000:00:19.0 eth0: MAC: 11, PHY: 12, PBA No: FFFFFF-0FF
[  844.786548] [drm] Memory usable by graphics device = 4096M
[  844.788371] [drm] Replacing VGA console driver
[  844.790657] Console: switching to colour dummy device 80x25
[  844.797719] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[  844.799960] [drm] Driver supports precise vblank timestamp query.
[  844.802278] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=io+mem:owns=io+mem
[  844.914831] [drm] failed to retrieve link info, disabling eDP
[  845.136025] fbcon: inteldrmfb (fb0) is primary device
[  845.141763] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[  845.284073] Console: switching to colour frame buffer device 320x90
[  845.291015] acpi device:11: registered as cooling_device9
[  845.291091] input: Video Bus as /devices/LNXSYSTM:00/device:00/PNP0A08:00/LNXVIDEO:00/input/input5
[  845.291175] [drm] Initialized i915 1.6.0 20160229 for 0000:00:02.0 on minor 0
[  845.298955] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[                                    Mountin[ 1021.672996] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: (null)
[ 1021.866784] systemd-journald[112]: Received SIGTERM from PID 1 (systemd).
[ 1021.894472] SELinux:  Disabled at runtime.
[ 1021.937846] type=1404 audit(1499785164.900:2): selinux=0 auid=4294967295 ses=4294967295
[ 1021.955126] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 1021.958293] systemd[1]: Inserted module 'ip_tables'
[ 1021.976882] systemd-sysv-generator[391]: Overwriting existing symlink /run/systemd/generator.late/winccoa.service with real service
[ 1022.064584] RPC: Registered named UNIX socket transport module.
[ 1022.064584] RPC: Registered udp transport module.
[ 1022.064585] RPC: Registered tcp transport module.
[ 1022.064585] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1022.094937] Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
[ 1022.111998] EXT4-fs (sda1): re-mounted. Opts: (null)
[ 1022.121041] openafs: loading out-of-tree module taints kernel.
[ 1022.121042] openafs: module license 'http://www.openafs.org/dl/license10.html' taints kernel.
[ 1022.121042] Disabling lock debugging due to kernel taint
[ 1022.121536] openafs: module verification failed: signature and/or required key missing - tainting kernel
[ 1022.138273] Key type afs_pag registered
[ 1022.377928] i2c_designware: probe of INT3432:00 failed with error -2
[ 1022.390914] i2c_designware: probe of INT3433:00 failed with error -2
[ 1022.431305] ACPI Warning: SystemIO range 0x0000000000003040-0x000000000000305f conflicts with OpRegion 0x0000000000003040-0x000000000000304f (\_SB_.PCI0.SBUS.SMBI) (20130517/utaddress-254)
[ 1022.439301] ACPI: If an ACPI driver is available for this device, you should use it instead of the native driver
[ 1022.445618] input: PC Speaker as /devices/platform/pcspkr/input/input6
[ 1022.452392] sd 3:0:0:0: Attached scsi generic sg0 type 0
[ 1022.463094] AES CTR mode by8 optimization enabled
[ 1022.475971] alg: No test for __gcm-aes-aesni (__driver-gcm-aes-aesni)
[ 1022.488794] snd_hda_intel 0000:00:03.0: bound 0000:00:02.0 (ops i915_audio_component_bind_ops [i915])
[ 1022.503185] alg: No test for crc32 (crc32-pclmul)
[ 1022.524682] iTCO_vendor_support: vendor-support=0
[ 1022.527747] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[ 1022.527818] iTCO_wdt: Found a Lynx Point_LP TCO device (Version=2, TCOBASE=0x1860)
[ 1022.527907] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[ 1022.528706] snd_hda_codec_realtek hdaudioC1D0: autoconfig for ALC283: line_outs=1 (0x21/0x0/0x0/0x0/0x0) type:hp
[ 1022.528708] snd_hda_codec_realtek hdaudioC1D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[ 1022.528709] snd_hda_codec_realtek hdaudioC1D0:    hp_outs=0 (0x0/0x0/0x0/0x0/0x0)
[ 1022.528710] snd_hda_codec_realtek hdaudioC1D0:    mono: mono_out=0x0
[ 1022.528711] snd_hda_codec_realtek hdaudioC1D0:    inputs:
[ 1022.528712] snd_hda_codec_realtek hdaudioC1D0:      Mic=0x19
[ 1022.553190] input: HDA Intel HDMI HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:03.0/sound/card0/input7
[ 1022.553249] input: HDA Intel HDMI HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:03.0/sound/card0/input8
[ 1022.554197] input: HDA Intel HDMI HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:03.0/sound/card0/input9
[ 1022.576583] intel_rapl: Found RAPL domain package
[ 1022.576586] intel_rapl: Found RAPL domain core
[ 1022.576591] intel_rapl: Found RAPL domain uncore
[ 1022.576595] intel_rapl: Found RAPL domain dram
[ 1022.588829] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card1/input10
[ 1022.588863] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card1/input11
[ 1022.634230] device-mapper: uevent: version 1.0.3
[ 1022.637537] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[ 1022.748791] Adding 8127484k swap on /dev/sda3.  Priority:-1 extents:1 across:8127484k SSFS
[ 1022.776227] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: (null)
[ 1022.783419] EXT4-fs (sda5): mounted filesystem with ordered data mode. Opts: (null)
[ 1022.791326] systemd-journald[393]: Received request to flush runtime journal from PID 1
[ 1022.817443] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)

wesco
Posts: 1
Joined: 2018/02/09 11:00:58

Re: Serial over lan

Postby wesco » 2018/02/09 11:05:34

Hi,
Did you find a solution?

I have the same problem with Supermicro X9SRi-F (bios 3.0a and 3.2a). Bios 1.0c works ok.
Not a single message from systemd is printed every 30 seconds. Also these are followed by kernel messages which is a mess.
I never get a login prompt - maybe this would show up after several hours.

diosim
Posts: 10
Joined: 2017/06/02 09:32:25

Re: Serial over lan

Postby diosim » 2018/02/09 15:51:51

Hello,

Yes, the issue was coming from the hardware, I had to try on a different hardware, and it worked straight, just had to put on the grub console ttyS`on Centos 7.4.

Thanks.