Attachment 586526 Details for Bug 691922 – dmesg log after update

dmesg log after update

dmesg_after_update.log (text/plain), 17.96 KB, created by Jan Breig on 2019-08-11 02:20:42 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jan Breig

Created: 2019-08-11 02:20:42 UTC

Size: 17.96 KB

patch

obsolete

>[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd034]
>[    0.000000] Linux version 4.19.34-raspberrypi-v8+ (root@pibox) (gcc version 8.3.0 (Gentoo 8.3.0-r1 p1.1)) #3 SMP PREEMPT Wed May 15 03:45:29 CEST 2019
>[    0.000000] Machine model: Raspberry Pi 3 Model B Rev 1.2
>[    0.000000] cma: Reserved 256 MiB at 0x000000001ec00000
>[    0.000000] On node 0 totalpages: 255488
>[    0.000000]   DMA32 zone: 3992 pages used for memmap
>[    0.000000]   DMA32 zone: 0 pages reserved
>[    0.000000]   DMA32 zone: 255488 pages, LIFO batch:63
>[    0.000000] random: get_random_bytes called from start_kernel+0xa0/0x458 with crng_init=0
>[    0.000000] percpu: Embedded 23 pages/cpu @(____ptrval____) s56984 r8192 d29032 u94208
>[    0.000000] pcpu-alloc: s56984 r8192 d29032 u94208 alloc=23*4096
>[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
>[    0.000000] Detected VIPT I-cache on CPU0
>[    0.000000] CPU features: enabling workaround for ARM erratum 843419
>[    0.000000] CPU features: enabling workaround for ARM erratum 845719
>[    0.000000] CPU features: detected: Kernel page table isolation (KPTI)
>[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 251496
>[    0.000000] Kernel command line: coherent_pool=1M 8250.nr_uarts=0 cma=256M  vc_mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000  root=/dev/mmcblk0p3 rootfstype=f2fs rootwait
>[    0.000000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
>[    0.000000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
>[    0.000000] Memory: 726620K/1021952K available (7614K kernel code, 884K rwdata, 2348K rodata, 2688K init, 924K bss, 33188K reserved, 262144K cma-reserved)
>[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
>[    0.000000] ftrace: allocating 26525 entries in 104 pages
>[    0.000000] rcu: Preemptible hierarchical RCU implementation.
>[    0.000000] 	Tasks RCU enabled.
>[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
>[    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.000257] Console: colour dummy device 80x25
>[    0.000810] console [tty0] enabled
>[    0.000927] Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=19200)
>[    0.000986] pid_max: default: 32768 minimum: 301
>[    0.001389] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
>[    0.001445] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
>[    0.002786] Disabling memory control group subsystem
>[    0.009147] ASID allocator initialised with 32768 entries
>[    0.011144] rcu: Hierarchical SRCU implementation.
>[    0.016237] smp: Bringing up secondary CPUs ...
>[    0.024488] Detected VIPT I-cache on CPU1
>[    0.024557] CPU1: Booted secondary processor 0x0000000001 [0x410fd034]
>[    0.032616] Detected VIPT I-cache on CPU2
>[    0.032663] CPU2: Booted secondary processor 0x0000000002 [0x410fd034]
>[    0.040777] Detected VIPT I-cache on CPU3
>[    0.040822] CPU3: Booted secondary processor 0x0000000003 [0x410fd034]
>[    0.041024] smp: Brought up 1 node, 4 CPUs
>[    0.041174] SMP: Total of 4 processors activated.
>[    0.041208] CPU features: detected: 32-bit EL0 Support
>[    0.044131] CPU: All CPU(s) started at EL2
>[    0.044205] alternatives: patching kernel code
>[    0.046118] devtmpfs: initialized
>[    0.062296] Enabled cp15_barrier support
>[    0.062361] Enabled setend support
>[    0.062948] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
>[    0.063010] futex hash table entries: 1024 (order: 4, 65536 bytes)
>[    0.074989] pinctrl core: initialized pinctrl subsystem
>[    0.075916] NET: Registered protocol family 16
>[    0.082083] cpuidle: using governor menu
>[    0.082482] vdso: 2 pages (1 code @ (____ptrval____), 1 data @ (____ptrval____))
>[    0.082529] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
>[    0.087799] DMA: preallocated 1024 KiB pool for atomic allocations
>[    0.087986] Serial: AMBA PL011 UART driver
>[    0.092794] bcm2835-mbox 3f00b880.mailbox: mailbox enabled
>[    0.133723] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
>[    0.135285] bcm2835-dma 3f007000.dma: DMA legacy API manager at (____ptrval____), dmachans=0x1
>[    0.137761] SCSI subsystem initialized
>[    0.138080] usbcore: registered new interface driver usbfs
>[    0.138174] usbcore: registered new interface driver hub
>[    0.138351] usbcore: registered new device driver usb
>[    0.140537] raspberrypi-firmware soc:firmware: Attached to firmware from 2019-07-09 14:41, variant start_cd
>[    0.141551] raspberrypi-firmware soc:firmware: Firmware hash is 6c3fe3f096a93de3b34252ad98cdccadeb534be2
>[    0.144355] clocksource: Switched to clocksource arch_sys_counter
>[    0.268765] VFS: Disk quotas dquot_6.6.0
>[    0.268905] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
>[    0.269157] FS-Cache: Loaded
>[    0.269665] CacheFiles: Loaded
>[    0.283009] NET: Registered protocol family 2
>[    0.284010] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes)
>[    0.284085] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
>[    0.284228] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
>[    0.284515] TCP: Hash tables configured (established 8192 bind 8192)
>[    0.284792] UDP hash table entries: 512 (order: 2, 16384 bytes)
>[    0.284869] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
>[    0.285221] NET: Registered protocol family 1
>[    0.286177] RPC: Registered named UNIX socket transport module.
>[    0.286213] RPC: Registered udp transport module.
>[    0.286243] RPC: Registered tcp transport module.
>[    0.286273] RPC: Registered tcp NFSv4.1 backchannel transport module.
>[    0.289590] Initialise system trusted keyrings
>[    0.289993] workingset: timestamp_bits=46 max_order=18 bucket_order=0
>[    0.302066] FS-Cache: Netfs 'nfs' registered for caching
>[    0.303212] NFS: Registering the id_resolver key type
>[    0.303291] Key type id_resolver registered
>[    0.303322] Key type id_legacy registered
>[    0.307291] Key type asymmetric registered
>[    0.307375] Asymmetric key parser 'x509' registered
>[    0.307465] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
>[    0.307752] io scheduler noop registered
>[    0.307786] io scheduler deadline registered
>[    0.308031] io scheduler cfq registered (default)
>[    0.308067] io scheduler mq-deadline registered
>[    0.308099] io scheduler kyber registered
>[    0.316531] bcm2835-rng 3f104000.rng: hwrng registered
>[    0.316825] vc-mem: phys_addr:0x00000000 mem_base=0x3f000000 mem_size:0x3f600000(1014 MiB)
>[    0.317880] gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
>[    0.318232] cacheinfo: Unable to detect cache hierarchy for CPU 0
>[    0.333844] brd: module loaded
>[    0.349220] loop: module loaded
>[    0.349266] Loading iSCSI transport class v2.0-870.
>[    0.350638] libphy: Fixed MDIO Bus: probed
>[    0.350789] usbcore: registered new interface driver lan78xx
>[    0.350896] usbcore: registered new interface driver smsc95xx
>[    0.350941] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
>[    0.378996] dwc_otg 3f980000.usb: base=(____ptrval____)
>[    0.579672] Core Release: 2.80a
>[    0.579705] Setting default values for core params
>[    0.579755] Finished setting default values for core params
>[    0.780425] Using Buffer DMA mode
>[    0.780456] Periodic Transfer Interrupt Enhancement - disabled
>[    0.780489] Multiprocessor Interrupt Enhancement - disabled
>[    0.780522] OTG VER PARAM: 0, OTG VER FLAG: 0
>[    0.780556] Dedicated Tx FIFOs mode
>[    0.781777] WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = ffffff8008259000 dma = 0x00000000ded04000 len=9024
>[    0.781844] FIQ FSM acceleration enabled for :
>               Non-periodic Split Transactions
>               Periodic Split Transactions
>               High-Speed Isochronous Endpoints
>               Interrupt/Control Split Transaction hack enabled
>[    0.781929] dwc_otg: Microframe scheduler enabled
>[    0.782077] WARN::hcd_init_fiq:484: MPHI regs_base at ffffff8008045000
>[    0.782249] dwc_otg 3f980000.usb: DWC OTG Controller
>[    0.782307] dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
>[    0.782429] dwc_otg 3f980000.usb: irq 41, io mem 0x00000000
>[    0.782503] Init: Port Power? op_state=1
>[    0.782531] Init: Power Port (0)
>[    0.782959] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
>[    0.783007] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
>[    0.783048] usb usb1: Product: DWC OTG Controller
>[    0.783081] usb usb1: Manufacturer: Linux 4.19.34-raspberrypi-v8+ dwc_otg_hcd
>[    0.783119] usb usb1: SerialNumber: 3f980000.usb
>[    0.784034] hub 1-0:1.0: USB hub found
>[    0.784125] hub 1-0:1.0: 1 port detected
>[    0.785019] dwc_otg: FIQ enabled
>[    0.785031] dwc_otg: NAK holdoff enabled
>[    0.785042] dwc_otg: FIQ split-transaction FSM enabled
>[    0.785059] Module dwc_common_port init
>[    0.785938] usbcore: registered new interface driver usb-storage
>[    0.787250] bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
>[    0.787752] bcm2835-cpufreq: min=600000 max=1200000
>[    0.788615] sdhci: Secure Digital Host Controller Interface driver
>[    0.788651] sdhci: Copyright(c) Pierre Ossman
>[    0.789220] mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
>[    0.789809] sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
>[    0.789929] sdhci-pltfm: SDHCI platform and OF driver helper
>[    0.791263] ledtrig-cpu: registered to indicate activity on CPUs
>[    0.791436] hidraw: raw HID events driver (C) Jiri Kosina
>[    0.791562] usbcore: registered new interface driver usbhid
>[    0.791578] usbhid: USB HID core driver
>[    0.791774] Initializing XFRM netlink socket
>[    0.791809] NET: Registered protocol family 17
>[    0.791905] Key type dns_resolver registered
>[    0.792555] registered taskstats version 1
>[    0.792578] Loading compiled-in X.509 certificates
>[    0.799203] uart-pl011 3f201000.serial: cts_event_workaround enabled
>[    0.799295] 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 66, base_baud = 0) is a PL011 rev2
>[    0.800886] mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
>[    0.800910] mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
>[    0.827826] sdhost: log_buf @ (____ptrval____) (ded07000)
>[    0.854984] mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
>[    0.856699] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
>[    0.858407] mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
>[    0.861449] mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
>[    0.876795] mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
>[    0.879505] of_cfs_init
>[    0.879723] of_cfs_init: OK
>[    0.880787] Waiting for root device /dev/mmcblk0p3...
>[    0.939698] random: fast init done
>[    0.964697] mmc0: host does not support reading read-only switch, assuming write-enable
>[    0.968707] mmc0: new high speed SDHC card at address e624
>[    0.971155] mmcblk0: mmc0:e624 SC32G 29.7 GiB
>[    0.974849]  mmcblk0: p1 p2 p3
>[    0.980666] mmc1: new high speed SDIO card at address 0001
>[    1.002591] Indeed it is in host mode hprt0 = 00021501
>[    1.177430] usb 1-1: new high-speed USB device number 2 using dwc_otg
>[    1.275969] random: crng init done
>[    1.904818] irq 41: nobody cared (try booting with the "irqpoll" option)
>[    1.904846] CPU: 3 PID: 0 Comm: swapper/3 Not tainted 4.19.34-raspberrypi-v8+ #3
>[    1.904863] Hardware name: Raspberry Pi 3 Model B Rev 1.2 (DT)
>[    1.904877] Call trace:
>[    1.904899]  dump_backtrace+0x0/0x158
>[    1.904914]  show_stack+0x24/0x30
>[    1.904928]  dump_stack+0x9c/0xd4
>[    1.904944]  __report_bad_irq+0x54/0xe0
>[    1.904957]  note_interrupt+0x278/0x2dc
>[    1.904971]  handle_irq_event_percpu+0x8c/0x98
>[    1.904984]  handle_irq_event+0x50/0x80
>[    1.904997]  handle_level_irq+0xe8/0x170
>[    1.905010]  generic_handle_irq+0x34/0x50
>[    1.905025]  bcm2836_chained_handle_irq+0x4c/0x68
>[    1.905038]  generic_handle_irq+0x34/0x50
>[    1.905051]  __handle_domain_irq+0x98/0x108
>[    1.905064]  bcm2836_arm_irqchip_handle_irq+0x68/0xd0
>[    1.905078]  el1_irq+0xb4/0x130
>[    1.905090]  arch_cpu_idle+0x2c/0x238
>[    1.905104]  default_idle_call+0x38/0x44
>[    1.905117]  do_idle+0x240/0x258
>[    1.905129]  cpu_startup_entry+0x2c/0x30
>[    1.905143]  secondary_start_kernel+0x154/0x168
>[    1.905155] handlers:
>[    1.905170] [<000000007b203fd9>] dwc_otg_common_irq
>[    1.905192] [<00000000b67f7742>] dwc_otg_pcd_irq
>[    1.905209] [<000000003b7f20b8>] usb_hcd_irq
>[    1.905223] Disabling IRQ #41
>[    1.968168] F2FS-fs (mmcblk0p3): orphan cleanup on readonly fs
>[    1.970517] F2FS-fs (mmcblk0p3): recover fsync data on readonly fs
>[    1.971412] F2FS-fs (mmcblk0p3): Mounted with checkpoint version = 1823d946
>[    1.971514] VFS: Mounted root (f2fs filesystem) readonly on device 179:3.
>[    1.974186] devtmpfs: mounted
>[    1.980717] Freeing unused kernel memory: 2688K
>[    1.980808] Run /sbin/init as init process
>[    6.626418] Indeed it is in host mode hprt0 = 00001101
>[    6.806477] usb 1-1: device descriptor read/64, error -110
>[    7.248167] udevd[653]: starting version 3.2.8
>[    7.349758] udevd[653]: starting eudev-3.2.8
>[    8.030997] vchiq: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.058922] vchiq: vchiq_init_state: slot_zero = 000000006757cacf, is_master = 0
>[    8.249071] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.269144] bcm2835_alsa bcm2835_alsa: card created with 8 channels
>[    8.331722] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.333736] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
>[    8.333751] [vc_sm_connected_init]: start
>[    8.336621] vc_sm_cma_vchi_init: failed to open VCHI service (-1)
>[    8.336636] [vc_sm_connected_init]: failed to initialize shared memory service
>[    8.336643] [vc_sm_connected_init]: failed, ret -1
>[    8.426323] media: Linux media interface: v0.10
>[    8.477777] videodev: Linux video capture interface: v2.00
>[    8.525206] cfg80211: Loading compiled-in X.509 certificates for regulatory database
>[    8.681206] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.681481] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.682733] vc4-drm soc:gpu: bound 3f600000.firmwarekms (ops vc4_fkms_ops [vc4])
>[    8.695229] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.704694] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
>[    8.706345] vc4-drm soc:gpu: bound 3fc00000.v3d (ops vc4_v3d_ops [vc4])
>[    8.708655] [drm] Initialized vc4 0.0.0 20140616 for soc:gpu on minor 0
>[    8.708668] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
>[    8.708674] [drm] Driver supports precise vblank timestamp query.
>[    8.714416] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
>[    8.715036] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
>[    8.719654] bcm2835_mmal_vchiq: Failed to open VCHI service connection (status=-1)
>[    8.786028] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
>[    8.786241] usbcore: registered new interface driver brcmfmac
>[    8.958156] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
>[    8.958228] brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available
>[    8.958885] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f
>[   18.914525] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:30:crtc-0] flip_done timed out
>[   18.914882] Console: switching to colour frame buffer device 90x30
>[   22.498478] Indeed it is in host mode hprt0 = 00001101
>[   22.673423] usb 1-1: device descriptor read/64, error -110
>[   22.778534] Indeed it is in host mode hprt0 = 00001101
>[   22.953418] usb 1-1: new high-speed USB device number 3 using dwc_otg
>[   28.130445] Indeed it is in host mode hprt0 = 00001101
>[   28.305424] usb 1-1: device descriptor read/64, error -110
>[   29.154513] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:30:crtc-0] flip_done timed out
>[   39.394498] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:32:HDMI-A-1] flip_done timed out
>[   44.002445] Indeed it is in host mode hprt0 = 00001101
>[   44.177422] usb 1-1: device descriptor read/64, error -110
>[   44.282489] usb usb1-port1: attempt power cycle
>[   49.634510] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:primary] flip_done timed out
>[   59.874529] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:30:crtc-0] flip_done timed out
>[   59.883376] vc4-drm soc:gpu: fb0: DRM emulated frame buffer device
>[   60.745854] Adding 2097148k swap on /dev/mmcblk0p2.  Priority:-2 extents:1 across:2097148k SSFS
>[   63.122718] brcmfmac: power management disabled
>[   63.755321] 8021q: 802.1Q VLAN Support v1.8
>[   63.895515] NET: Registered protocol family 10
>[   63.897258] IPv6: ADDRCONF(NETDEV_UP): wlan0: link is not ready
>[   63.897698] Segment Routing with IPv6
>[   64.897911] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
>[   71.138563] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CRTC:30:crtc-0] flip_done timed out
>[   81.378617] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [CONNECTOR:32:HDMI-A-1] flip_done timed out
>[   91.618683] [drm:drm_atomic_helper_wait_for_dependencies [drm_kms_helper]] *ERROR* [PLANE:28:primary] flip_done timed out
>[  101.858727] [drm:drm_atomic_helper_wait_for_flip_done [drm_kms_helper]] *ERROR* [CRTC:30:crtc-0] flip_done timed out

Actions: View

Attachments on bug 691922: 586522 | 586524 | 586526