skiboot log

gistfile1.txt

$ /opt/ibm/systemsim-p8/run/pegasus/power8 -f skiboot.tcl
Licensed Materials - Property of IBM.
(C) Copyright IBM Corporation 2001, 2014
All Rights Reserved.
Using initial run script skiboot.tcl
Starting mambo on *guoliangc* (pid = 14593) at Sun Feb  8 18:00:18 2015
With command: /opt/ibm/systemsim-p8/bin/systemsim-pegasus -f skiboot.tcl
No network support selected
INFO: 0: (0): !!!!!! Simulator now in TURBO mode !!!!!!
9470983: (9470983): [4938167,5] SkiBoot skiboot-4.1.1 starting...
9470983: (9470983): [4942992,5] initial console log level: memory 7, driver 5
9470983: (9470983): [4947512,6] CPU: P8 generation processor(max 8 threads/core)
9470983: (9470983): [4951874,7] CPU: Boot CPU PIR is 0x0000 PVR is 0x004b0100
9470983: (9470983): [4956564,7] CPU: Initial max PIR set to 0x1fff
9470983: (9470983): [9293865,5] OPAL table: 0x300940a0 .. 0x300944c0, branch table: 0x30002000
9470983: (9470983): [9300903,5] FDT: Parsing fdt @0x1f00000
9470983: (9470983): [9467972,5] Enabling Mambo console
9480919: (9480919): [9475452,5] XSCOM: chip 0x0 at 0x1a0000000000 [P8E DD2.1]
9526606: (9526606): [9519584,3] HBRT: Mem region 'ibm,hbrt-code-image' not found !
9530318: (9530318): [9526826,3] HBRT: No ibm,hbrt-code-image found.
9554366: (9554366): [9550449,5] PLAT: Detected generic platform
13846077: (13846077): [13843239,3] SLW: No image found !
13896642: (13896642): [13893753,5] PCI: Resetting PHBs...
13900648: (13900648): [13897810,5] PCI: Probing slots...
13908801: (13908801): [13905810,5] Releasing unused memory:
13916047: (13916047): [13909088,5]     ibm,firmware-heap: 12072/12582912 used
13927241: (13927241): [13916329,5]     ibm,firmware-allocs-memory@0: 1056768/106954752 used
13931680: (13931680): [13929046,5] Reserved regions:
13940459: (13940459): [13932149,5]   0x000031a00000..0000399fffff : ibm,firmware-stacks
13948637: (13948637): [13940905,5]   0x000030e00000..0000319fffff : ibm,firmware-data
13956815: (13956815): [13949083,5]   0x000030200000..000030dfffff : ibm,firmware-heap
13964993: (13964993): [13957261,5]   0x000030000000..0000301fffff : ibm,firmware-code
13977072: (13977072): [13965567,5]   0x000039a00000..000039b01fff : ibm,firmware-allocs-memory@0
13983996: (13983996): [13980335,5] Assuming kernel at 0x20000000
13989124: (13989124): [13984209,5] INIT: Kernel loaded, size: 0 bytes (0 = unknown preload)
13993547: (13993547): [13989400,5] INIT: 32-bit kernel entry at 0x2001015c
14011372: (14011372): [13994647,3] OCC: No HOMER detected, assuming no pstates
14015369: (14015369): [14011613,3] ELOG: Error getting buffer to log error
17911077: (17911077): [17904760,5] INIT: Starting kernel at 0x2001015c, fdt at 0x30203180 (size 0x1030)
459016858: (459016855): [459012561,3] OPAL: Trying a CPU re-init with flags: 0x1
459025536: (459025533): [459018004,3] SLW: Not found on chip 0
459029593: (459029590): [459025777,3] ELOG: Error getting buffer to log error
463763868: (463763863): [    0.000000] OPAL V3 detected !
463808527: (463808522): [    0.000000] Using PowerNV machine description
463872636: (463872631): [    0.000000] bootconsole [udbg0] enabled
463930854: (463930849): [    0.000000] CPU maps initialized for 1 thread per core
464011646: (464011641):  -> smp_release_cpus()
464040077: (464040072): spinning_secondaries = 0
464070462: (464070457):  <- smp_release_cpus()
464101937: (464101932): [    0.000000] Starting Linux PPC64 #2 SMP Sun Feb 8 17:41:07 CST 2015
464188874: (464188869): [    0.000000] -----------------------------------------------------
464273611: (464273606): [    0.000000] ppc64_pft_size                = 0x0
464337678: (464337673): [    0.000000] physicalMemorySize            = 0x40000000
464410069: (464410064): [    0.000000] htab_address                  = 0xc00000003f000000
464491435: (464491430): [    0.000000] htab_hash_mask                = 0x1ffff
464559870: (464559865): [    0.000000] -----------------------------------------------------
464641483: (464641478):  <- setup_system()
464669646: (464669641): [    0.000000] Linux version 3.17.2 (liang@guoliangc) (gcc version 4.8.3 (Buildroot 2014.08-git-g047bf10) ) #2 SMP Sun Feb 8 17:41:07 CST 2015
464841198: (464841193): [    0.000000] [boot]0012 Setup Arch
590765495: (590765487): [    0.000000] Zone ranges:
590801443: (590801435): [    0.000000]   DMA      [mem 0x00000000-0x3fffffff]
590869941: (590869933): [    0.000000]   Normal   empty
590912856: (590912848): [    0.000000] Movable zone start for each node
590976158: (590976150): [    0.000000] Early memory node ranges
591028301: (591028293): [    0.000000]   node   0: [mem 0x00000000-0x3fffffff]
593196165: (593196157): [    0.000000] [boot]0015 Setup Done
593446085: (593446077): [    0.000000] PERCPU: Embedded 1 pages/cpu @c000000001d00000 s35712 r0 d29824 u1048576
593614577: (593614569): [    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 16370
593723303: (593723295): [    0.000000] Policy zone: DMA
593765422: (593765414): [    0.000000] Kernel command line: rw ppcdbg=phb_init
593988402: (593988394): [    0.000000] PID hash table entries: 4096 (order: -1, 32768 bytes)
594085064: (594085056): [    0.000000] Sorting __ex_table...
597873800: (597873792): [    0.000000] Memory: 827136K/1048576K available (13440K kernel code, 1920K rwdata, 5372K rodata, 6272K init, 1692K bss, 221440K reserved)
598398209: (598398199): [    0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=1, Nodes=256
598711996: (598711986): [    0.000000] Hierarchical RCU implementation.
598773120: (598773110): [    0.000000] ^IRCU restricting CPUs from NR_CPUS=2048 to nr_cpu_ids=1.
598862074: (598862064): [    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
598960925: (598960915): [    0.000000] NR_IRQS:512 nr_irqs:512 16
599128948: (599128938): [    0.000000] ICS OPAL backend registered
599216777: (599216767): [599213035,3] OPAL: Called with bad token 3 !
604356551: (604356541): [    0.000004] clocksource: timebase mult[1f40000] shift[24] registered
604509284: (604509274): [    0.000302] Console: colour dummy device 80x25
604573933: (604573923): [    0.000427] console [hvc0] enabled
604578616: (604578606): [    0.000427] console [hvc0] enabled
604628851: (604628841): [    0.000534] bootconsole [udbg0] disabled
604633858: (604633848): [    0.000534] bootconsole [udbg0] disabled
604746450: (604746440): [    0.000764] pid_max: default: 32768 minimum: 301
604836876: (604836866): [    0.000940] Dentry cache hash table entries: 131072 (order: 4, 1048576 bytes)
606177811: (606177801): [    0.003559] Inode-cache hash table entries: 65536 (order: 3, 524288 bytes)
606858403: (606858393): [    0.004889] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes)
606875625: (606875615): [    0.004922] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes)
607555026: (607555016): [    0.006250] EEH: PowerNV platform initialized
607601615: (607601605): [    0.006340] POWER8 performance monitor hardware support registered
608230687: (608230675): [    0.007569] Brought up 1 CPUs
609253260: (609253248): [    0.009566] devtmpfs: initialized
609690821: (609690807): [    0.010421] EEH: devices created
609806060: (609806046): [    0.010646] xor: measuring software checksum speed
660814383: (660809369): [    0.110272]    8regs     :   915.200 MB/sec
712067398: (712057384): [    0.210375]    8regs_prefetch:   748.800 MB/sec
763268814: (763253800): [    0.310378]    32regs    :   915.200 MB/sec
814523422: (814503408): [    0.410485]    32regs_prefetch:   748.800 MB/sec
865724391: (865699377): [    0.510487]    altivec   :  1280.000 MB/sec
865735117: (865710103): [    0.510507] xor: using function: altivec (1280.000 MB/sec)
866441766: (866416752): [    0.511888] NET: Registered protocol family 16
866853464: (866828450): [    0.512692] cpuidle: using governor ladder
866868404: (866843390): [    0.512721] cpuidle: using governor menu
867253636: (867228622): [    0.513473] PCI: Probing PCI hardware
867378850: (867353836): [    0.513718] ------------[ cut here ]------------
867390334: (867365320): [    0.513740] WARNING: at mm/page_alloc.c:2545
867399730: (867374716): [    0.513761] Modules linked in:
867413280: (867388266): [    0.513785] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.17.2 #2
867428185: (867403171): [    0.513814] task: c00000003d3f0000 ti: c00000003d400000 task.ti: c00000003d400000
867444867: (867419853): [    0.513847] NIP: c00000000012e2bc LR: c00000000012e204 CTR: c0000000000af620
867461139: (867436125): [    0.513878] REGS: c00000003d403540 TRAP: 0700   Not tainted  (3.17.2)
867473482: (867448468): [    0.513905] MSR: 9000000000029032 <SF,HV,EE,ME,IR,DR,RI>  CR: 24002004  XER: 22000000
867510106: (867485092): [    0.513976] CFAR: c00000000012e208 SOFTE: 1 
867519458: (867494444): GPR00: c00000000012e204 c00000003d4037c0 c000000001a0fb08 0000000000000000 
867541150: (867516136): GPR04: 0000000000000000 c0000000018dfb08 c00000003d4036a0 c000000001a6fb08 
867562842: (867537828): GPR08: 0000000000000000 0000000000000001 0000000000000000 0000000000000012 
867583991: (867558977): GPR12: 0000000044002008 c00000000fe80000 c00000000000b5f0 0000000000000000 
867605638: (867580624): GPR16: 0000000000000000 0000000000000000 0000000000000000 0000000000000000 
867626757: (867601743): GPR20: 0000000000000000 0000000000000000 000000000000c0d0 000000000000c0d0 
867647988: (867622974): GPR24: c000000001a68e60 0000000000000000 0000000000000000 0000000000000001 
867669316: (867644302): GPR28: 0000000000000012 0000000000000001 0000000000000000 c00000003eff4b00 
867699918: (867674904): [    0.514345] NIP [c00000000012e2bc] .__alloc_pages_nodemask+0x1fc/0x810
867717123: (867692109): [    0.514378] LR [c00000000012e204] .__alloc_pages_nodemask+0x144/0x810
867729848: (867704834): [    0.514404] Call Trace:
867740714: (867715700): [    0.514426] [c00000003d4037c0] [c00000000012e204] .__alloc_pages_nodemask+0x144/0x810 (unreliable)
867764189: (867739175): [    0.514472] [c00000003d403930] [c000000000165898] .alloc_page_interleave+0x48/0xb0
867784603: (867759589): [    0.514512] [c00000003d4039c0] [c00000000012b380] .alloc_kmem_pages+0x10/0x30
867802592: (867777578): [    0.514547] [c00000003d403a30] [c00000000014812c] .kmalloc_order+0x1c/0x80
867822061: (867797047): [    0.514585] [c00000003d403aa0] [c00000000016d680] .__kmalloc+0x1a0/0x1b0
867841988: (867816974): [    0.514624] [c00000003d403b40] [c0000000012833cc] .__machine_initcall_powernv_opal_init+0x264/0x47c
867864848: (867839834): [    0.514669] [c00000003d403c10] [c00000000000ad14] .do_one_initcall+0xd4/0x270
867883416: (867858402): [    0.514705] [c00000003d403d00] [c0000000012744c4] .kernel_init_freeable+0x190/0x270
867904240: (867879226): [    0.514746] [c00000003d403db0] [c00000000000b60c] .kernel_init+0x1c/0x130
867923454: (867898440): [    0.514783] [c00000003d403e30] [c00000000000951c] .ret_from_kernel_thread+0x58/0xbc
867939589: (867914575): [    0.514815] Instruction dump:
867948576: (867923562): [    0.514833] 81290014 792a6fe3 40820404 2b9c0008 e9210090 f9210088 409d005c 7ae4bfe3 
867978367: (867953353): [    0.514891] 40a20034 3ca2ffed 8925a08f 69290001 <0b090000> 2fa90000 41be001c 39200001 
868013656: (867988642): [    0.514958] ---[ end trace d8ed17cc94abc32a ]---
868027195: (868002181): [868023333,3] OPAL: Called with bad token 101 !
868032505: (868007491): [    0.514994] DUMP: Failed to register kernel log buffer. rc = -1
868047797: (868022783): [868043995,3] OPAL: Called with bad token 74 !
868065838: (868040824): [868062036,3] OPAL: Called with bad token 91 !
868077957: (868052943): [    0.515083] SYSPARAM: Opal sysparam node not found
963049429: (963023413): [    0.700575] raid6: altivecx1   708 MB/s
^CINFO: 995671273: (995644258): ** Execution stopped: user interrupt,  **
995671273: ** finished running 995644258 instructions **