Attachment 946319 Details for Bug 1152014 – File: anaconda-tb

File: anaconda-tb

anaconda-tb (text/plain), 51.00 KB, created by Jakub Čajka on 2014-10-13 11:01:41 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Jakub Čajka

Created: 2014-10-13 11:01:41 UTC

Size: 51.00 KB

patch

obsolete

>anaconda 21.48.8-1 exception report
>Traceback (most recent call first):
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 348, in run
>    return self.trans[which]
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 693, in run
>    return self.form.run()
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 673, in runOnce
>    result = self.run(x, y)
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 833, in ButtonChoiceWindow
>    return bb.buttonPressed(g.runOnce(x, y))
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/rescue.py", line 299, in doRescue
>    [_("Continue"), _("Read-Only"), _("Skip")] )
>  File "/sbin/anaconda", line 1310, in <module>
>    rescue.doRescue(anaconda.intf, anaconda.rescue_mount, ksdata)
>KeyError: 858215776
>
>Local variables in innermost frame:
>what: widget
>self: <snack.Form instance at 0x3fff91daa3f8>
>which: 858215776
>
>
>Anaconda instance, containing members:
>_instClass: DefaultInstall instance, containing members:
>_intf: RescueInterface instance, containing members:
>  _intf._meh_interface: TextIntf instance, containing members:
>    _intf._meh_interface._io: IOHandler instance, containing members:
>      _intf._meh_interface._io.out_func: <built-in function print>
>      _intf._meh_interface._io.in_func: <built-in function raw_input>
>    _intf._meh_interface.screen: None
>  _intf.screen: SnackScreen instance, containing members:
>    _intf.screen.width: 80
>    _intf.screen.height: 23
>updateSrc: None
>rootParts: None
>id: None
>mediaDevice: None
>methodstr: None
>proxyPassword: None
>desktop: Desktop instance, containing members:
>  desktop._runlevel: 3
>  desktop.desktop: None
>canReIPL: False
>xdriver: None
>stage2: None
>rescue_mount: True
>_bootloader: None
>_payload: YumPayload instance, containing members:
>  _payload._groups: None
>  _payload.instclass: None
>  _payload._rescueVersionList: []
>  _payload.install_device: None
>  _payload._environmentAddons: {}
>  _payload._root_dir: /tmp/yum.root
>  _payload._repos_dir: /etc/yum.repos.d,/etc/anaconda.repos.d,/tmp/updates/anaconda.repos.d,/tmp/product/anaconda.repos.d
>  _payload._setup: False
>  _payload.storage: None
>  _payload.requiredGroups: []
>  _payload._base_repo: None
>  _payload.txID: None
>  _payload._rpm_macros: []
>  _payload._base_repo_lock: <_RLock owner=None count=0>
>  _payload._createdInitrds: False
>  _payload._packages: []
>  _payload.requiredPackages: []
>  _payload._space_required: 2861.02 MiB
>  _payload._yum: Skipped
>  _payload.data: #version=DEVEL
># System authorization information
>auth --enableshadow --passalgo=sha512
>
># Run the Setup Agent on first boot
>firstboot --enable
># System language
>lang en_US.UTF-8
>
># SELinux configuration
>selinux --enforcing
># System bootloader configuration
>bootloader --location=mbr
>
>%addon com_redhat_kdump --disable --reserve-mb='256'
>
>%end
>
>  _payload._kernelVersionList: []
>displayMode: g
>ksdata: Already dumped (AnacondaKSHandler instance)
>proxyUsername: None
>dbus_session_connection: None
>dbus_inhibit_id: None
>mehConfig: Config instance, containing members:
>  mehConfig.programArch: ppc64
>  mehConfig.callbackDict: {'lsblk_output': (<function lsblk_callback at 0x3fff91ee47d0>, True), 'type': (<function <lambda> at 0x3fff91ee4a28>, True), 'addons': (<function list_addons_callback at 0x3fff91ee4938>, False), 'nmcli_dev_list': (<function nmcli_dev_list_callback at 0x3fff91ee4848>, True)}
>  mehConfig.attrSkipList: [_intf._actions, _intf._currentAction._xklwrapper, _intf._currentAction._spokes["KeyboardSpoke"]._xkl_wrapper, _intf._currentAction._storage_playground, _intf._currentAction._spokes["CustomPartitioningSpoke"]._storage_playground, _intf._currentAction.language.translations, _intf._currentAction.language.locales, _intf._currentAction._spokes["PasswordSpoke"]._oldweak, _intf._currentAction._spokes["PasswordSpoke"]._password, _intf._currentAction._spokes["UserSpoke"]._password, _intf._currentAction._spokes["UserSpoke"]._oldweak, _intf.storage.bootloader.password, _intf.storage.data, _intf.storage.encryptionPassphrase, _bootloader.encrypted_password, _bootloader.password, payload._groups, payload._yum]
>  mehConfig.programVersion: 21.48.8-1
>  mehConfig.localSkipList: [passphrase, password, _oldweak, _password]
>  mehConfig.programName: anaconda
>  mehConfig.fileList: [/tmp/anaconda.log, /tmp/packaging.log, /tmp/program.log, /tmp/storage.log, /tmp/ifcfg.log, /tmp/dnf.log, /tmp/dnf.rpm.log, /tmp/yum.log, /mnt/sysimage/root/install.log, /proc/cmdline, /tmp/syslog]
>isHeadless: False
>reIPLMessage: None
>_storage: Blivet instance, containing members:
>  _storage.clearPartChoice: None
>  _storage.eddDict: {}
>  _storage.dasd: []
>  _storage.roots: []
>  _storage.zfcp: ZFCP instance, containing members:
>    _storage.zfcp.down: False
>    _storage.zfcp.hasReadConfig: True
>    _storage.zfcp.intf: None
>    _storage.zfcp.fcpdevs: set([])
>  _storage._defaultFSType: ext4
>  _storage.autoPartEscrowCert: None
>  _storage.iscsi: iscsi instance, containing members:
>    _storage.iscsi.initiatorSet: False
>    _storage.iscsi.ifaces: {}
>    _storage.iscsi.started: False
>    _storage.iscsi._initiator: 
>    _storage.iscsi.discovered_targets: {}
>    _storage.iscsi.ibftNodes: []
>  _storage.escrowCertificates: {}
>  _storage.fsset: FSSet instance, containing members:
>    _storage.fsset.origFStab: None
>    _storage.fsset._fstab_swaps: set([])
>    _storage.fsset._usb: None
>    _storage.fsset.devicetree: DeviceTree instance, containing members:
>      _storage.fsset.devicetree.dasd: []
>      _storage.fsset.devicetree.populated: False
>      _storage.fsset.devicetree.exclusiveDisks: []
>      _storage.fsset.devicetree._actions: []
>      _storage.fsset.devicetree.iscsi: Already dumped (iscsi instance)
>      _storage.fsset.devicetree._pvInfo: None
>      _storage.fsset.devicetree._cleanup: False
>      _storage.fsset.devicetree._devices: []
>      _storage.fsset.devicetree.ignoredDisks: []
>      _storage.fsset.devicetree._completed_actions: []
>      _storage.fsset.devicetree.names: []
>      _storage.fsset.devicetree.liveBackingDevice: None
>      _storage.fsset.devicetree.protectedDevNames: []
>      _storage.fsset.devicetree.unusedRaidMembers: []
>      _storage.fsset.devicetree.diskImages: {}
>      _storage.fsset.devicetree._hidden: []
>      _storage.fsset.devicetree.protectedDevSpecs: []
>      _storage.fsset.devicetree._lvInfo: None
>    _storage.fsset.preserveLines: []
>    _storage.fsset._run: None
>    _storage.fsset._devshm: None
>    _storage.fsset._dev: None
>    _storage.fsset.blkidTab: None
>    _storage.fsset._proc: None
>    _storage.fsset.active: False
>    _storage.fsset.cryptTab: None
>    _storage.fsset._devpts: None
>    _storage.fsset._sysfs: None
>    _storage.fsset._selinux: None
>  _storage.config: StorageDiscoveryConfig instance, containing members:
>    _storage.config.clearPartType: None
>    _storage.config.clearNonExistent: False
>    _storage.config.ignoredDisks: []
>    _storage.config.protectedDevSpecs: []
>    _storage.config.ignoreDiskInteractive: False
>    _storage.config.exclusiveDisks: []
>    _storage.config.clearPartDevices: []
>    _storage.config.zeroMbr: False
>    _storage.config.diskImages: {}
>    _storage.config.clearPartDisks: []
>    _storage.config.initializeDisks: False
>  _storage.size_sets: []
>  _storage.autoPartType: 2
>  _storage._bootloader: None
>  _storage.devicetree: Already dumped (DeviceTree instance)
>  _storage._dumpFile: /tmp/storage.state
>  _storage.ksdata: Already dumped (AnacondaKSHandler instance)
>  _storage.services: set([])
>  _storage.encryptionPassphrase: None
>  _storage.encryptionCipher: None
>  _storage.doAutoPart: False
>  _storage._nextID: 0
>  _storage.fcoe: fcoe instance, containing members:
>    _storage.fcoe.started: True
>    _storage.fcoe.nics: []
>    _storage.fcoe.lldpadStarted: False
>    _storage.fcoe.added_nics: []
>  _storage.autoPartitionRequests: []
>  _storage.autoPartAddBackupPassphrase: False
>  _storage.encryptedAutoPart: False
>dir: None
>opts: Namespace instance, containing members:
>  opts.mpath: True
>  opts.updateSrc: None
>  opts.selinux: True
>  opts.syslog: None
>  opts.eject: True
>  opts.leavebootorder: False
>  opts.images: []
>  opts.runres: None
>  opts.armPlatform: None
>  opts.dnf: False
>  opts.memcheck: True
>  opts.dirinstall: False
>  opts.xdriver: None
>  opts.display_mode: g
>  opts.stage2: None
>  opts.dmraid: True
>  opts.method: None
>  opts.vncpassword: 
>  opts.vnc: False
>  opts.rescue: True
>  opts.noverifyssl: False
>  opts.geoloc: None
>  opts.proxy: None
>  opts.vncconnect: None
>  opts.mpathfriendlynames: True
>  opts.lang: None
>  opts.askmethod: False
>  opts.liveinst: False
>  opts.loglevel: None
>  opts.multiLib: False
>  opts.rescue_nomount: False
>  opts.keymap: None
>  opts.ksfile: None
>  opts.ibft: True
>  opts.debug: False
>  opts.extlinux: False
>proxy: None
>
>Registered callbacks:
>addons:
>com_redhat_kdump
>
>
>/tmp/anaconda.log:
>10:51:10,751 INFO anaconda: /sbin/anaconda 21.48.8-1
>10:51:11,132 INFO anaconda: anaconda called with cmdline = ['/sbin/anaconda']
>10:51:11,133 INFO anaconda: Default encoding = utf-8 
>10:51:11,180 INFO anaconda: Parsing kickstart: /usr/share/anaconda/interactive-defaults.ks
>10:51:11,213 INFO anaconda: 4194304 kB (4096 MB) are available
>10:51:11,504 INFO anaconda: _Fedora is the highest priority installclass, using it
>10:51:34,905 DEBUG anaconda: running handleException
>10:51:34,907 CRIT anaconda: Traceback (most recent call last):
>
>  File "/sbin/anaconda", line 1310, in <module>
>    rescue.doRescue(anaconda.intf, anaconda.rescue_mount, ksdata)
>
>  File "/usr/lib64/python2.7/site-packages/pyanaconda/rescue.py", line 299, in doRescue
>    [_("Continue"), _("Read-Only"), _("Skip")] )
>
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 833, in ButtonChoiceWindow
>    return bb.buttonPressed(g.runOnce(x, y))
>
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 673, in runOnce
>    result = self.run(x, y)
>
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 693, in run
>    return self.form.run()
>
>  File "/usr/lib64/python2.7/site-packages/snack.py", line 348, in run
>    return self.trans[which]
>
>KeyError: 858215776
>
>10:51:35,273 DEBUG anaconda: Gtk cannot be initialized
>10:51:35,273 DEBUG anaconda: In the main thread, running exception handler
>
>
>/tmp/packaging.log:
>
>
>/tmp/program.log:
>10:51:11,214 INFO program: Running... udevadm trigger --action=change --subsystem-match=block
>10:51:11,237 DEBUG program: Return code: 0
>10:51:11,238 INFO program: Running... udevadm settle --timeout=300
>10:51:11,323 DEBUG program: Return code: 0
>10:51:11,325 INFO program: Running... modprobe fcoe
>10:51:11,384 DEBUG program: Return code: 0
>10:51:11,385 INFO program: Running... /usr/libexec/fcoe/fcoe_edd.sh -i
>10:51:11,399 ERR program: Error running /usr/libexec/fcoe/fcoe_edd.sh: No such file or directory
>
>
>/tmp/storage.log:
>10:51:11,324 INFO blivet: ISCSID is /sbin/iscsid
>10:51:11,325 INFO blivet: no initiator set
>10:51:11,399 INFO blivet: Failed to read FCoE EDD info: No such file or directory
>10:51:11,400 INFO blivet: no /etc/zfcp.conf; not configuring zfcp
>10:51:11,474 DEBUG blivet: trying to set new default fstype to 'ext4'
>10:51:11,497 DEBUG blivet:         Ext4FS.supported: supported: True ;
>10:51:11,498 DEBUG blivet: getFormat('ext4') returning Ext4FS instance with object id 0
>10:51:11,498 DEBUG blivet:      Ext4FS.supported: supported: True ;
>
>
>/tmp/ifcfg.log:
>
>
>/proc/cmdline:
>BOOT_IMAGE=/ppc/ppc64/vmlinuz rescue ro
>
>
>/tmp/syslog:
>10:51:05,672 INFO rsyslogd: [origin software="rsyslogd" swVersion="7.4.10" x-pid="1454" x-info="http://www.rsyslog.com"] start
>10:51:05,673 DEBUG kernel:[    0.000000] Allocated 2359296 bytes for 1024 pacas at c00000000fdc0000
>10:51:05,673 INFO kernel:[    0.000000] Using pSeries machine description
>10:51:05,673 INFO kernel:[    0.000000] Page sizes from device-tree:
>10:51:05,673 INFO kernel:[    0.000000] base_shift=12: shift=12, sllp=0x0000, avpnm=0x00000000, tlbiel=1, penc=0
>10:51:05,673 INFO kernel:[    0.000000] base_shift=16: shift=16, sllp=0x0110, avpnm=0x00000000, tlbiel=1, penc=1
>10:51:05,673 DEBUG kernel:[    0.000000] Page orders: linear mapping = 16, virtual = 16, io = 12, vmemmap = 16
>10:51:05,673 INFO kernel:[    0.000000] Using 1TB segments
>10:51:05,673 INFO kernel:[    0.000000] cma: Reserved 256 MiB at f0000000
>10:51:05,673 WARNING kernel:[    0.000000] Found initrd at 0xc000000003a00000:0xc000000005ec1ee8
>10:51:05,673 INFO kernel:[    0.000000] bootconsole [udbg0] enabled
>10:51:05,673 INFO kernel:[    0.000000] CPU maps initialized for 4 threads per core
>10:51:05,673 DEBUG kernel:[    0.000000]  (thread shift is 2)
>10:51:05,673 DEBUG kernel:[    0.000000] Freed 2293760 bytes for unused pacas
>10:51:05,673 WARNING kernel:[    0.000000] Starting Linux PPC64 #1 SMP Wed Oct 8 02:37:58 MST 2014
>10:51:05,673 WARNING kernel:[    0.000000] -----------------------------------------------------
>10:51:05,673 WARNING kernel:[    0.000000] ppc64_pft_size                = 0x19
>10:51:05,673 WARNING kernel:[    0.000000] physicalMemorySize            = 0x100000000
>10:51:05,673 WARNING kernel:[    0.000000] htab_hash_mask                = 0x3ffff
>10:51:05,673 WARNING kernel:[    0.000000] -----------------------------------------------------
>10:51:05,673 INFO kernel:[    0.000000] Initializing cgroup subsys cpuset
>10:51:05,673 INFO kernel:[    0.000000] Initializing cgroup subsys cpu
>10:51:05,673 INFO kernel:[    0.000000] Initializing cgroup subsys cpuacct
>10:51:05,673 NOTICE kernel:[    0.000000] Linux version 3.17.0-300.fc21.ppc64 (mockbuild@ppc-builder8.qa.fedoraproject.org) (gcc version 4.9.1 20140912 (Red Hat 4.9.1-9) (GCC) ) #1 SMP Wed Oct 8 02:37:58 MST 2014
>10:51:05,673 WARNING kernel:[    0.000000] [boot]0012 Setup Arch
>10:51:05,673 DEBUG kernel:[    0.000000] Node 0 Memory: 0x0-0x100000000
>10:51:05,673 INFO kernel:[    0.000000] PCI host bridge /pci@800000020000000  ranges:
>10:51:05,673 INFO kernel:[    0.000000]   IO 0x0000010080000000..0x000001008000ffff -> 0x0000000000000000
>10:51:05,673 INFO kernel:[    0.000000]  MEM 0x00000100a0000000..0x00000100bfffffff -> 0x0000000080000000 
>10:51:05,673 INFO kernel:[    0.000000] PPC64 nvram contains 65536 bytes
>10:51:05,673 WARNING kernel:[    0.000000] Unable to enable relocation on exceptions: -55
>10:51:05,673 WARNING kernel:[    0.000000] Zone ranges:
>10:51:05,673 WARNING kernel:[    0.000000]   DMA      [mem 0x00000000-0xffffffff]
>10:51:05,673 WARNING kernel:[    0.000000]   Normal   empty
>10:51:05,673 WARNING kernel:[    0.000000] Movable zone start for each node
>10:51:05,673 WARNING kernel:[    0.000000] Early memory node ranges
>10:51:05,673 WARNING kernel:[    0.000000]   node   0: [mem 0x00000000-0xffffffff]
>10:51:05,673 DEBUG kernel:[    0.000000] On node 0 totalpages: 65536
>10:51:05,673 DEBUG kernel:[    0.000000]   DMA zone: 56 pages used for memmap
>10:51:05,673 DEBUG kernel:[    0.000000]   DMA zone: 0 pages reserved
>10:51:05,673 DEBUG kernel:[    0.000000]   DMA zone: 65536 pages, LIFO batch:1
>10:51:05,673 WARNING kernel:[    0.000000] [boot]0015 Setup Done
>10:51:05,673 INFO kernel:[    0.000000] PERCPU: Embedded 3 pages/cpu @c000000001900000 s103168 r0 d93440 u262144
>10:51:05,673 DEBUG kernel:[    0.000000] pcpu-alloc: s103168 r0 d93440 u262144 alloc=1*1048576
>10:51:05,673 DEBUG kernel:[    0.000000] pcpu-alloc: [0] 0 1 2 3 [0] 4 5 6 7 
>10:51:05,673 WARNING kernel:[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 65480
>10:51:05,673 WARNING kernel:[    0.000000] Policy zone: DMA
>10:51:05,673 NOTICE kernel:[    0.000000] Kernel command line: BOOT_IMAGE=/ppc/ppc64/vmlinuz rescue ro
>10:51:05,673 INFO kernel:[    0.000000] PID hash table entries: 4096 (order: -1, 32768 bytes)
>10:51:05,673 NOTICE kernel:[    0.000000] Sorting __ex_table...
>10:51:05,673 WARNING kernel:[    0.000000] Memory: 3796416K/4194304K available (9344K kernel code, 1856K rwdata, 3504K rodata, 7232K init, 2683K bss, 397888K reserved)
>10:51:05,673 INFO kernel:[    0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=8, Nodes=256
>10:51:05,673 INFO kernel:[    0.000000] Hierarchical RCU implementation.
>10:51:05,673 INFO kernel:[    0.000000] 	RCU dyntick-idle grace-period acceleration is enabled.
>10:51:05,673 INFO kernel:[    0.000000] 	RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=8.
>10:51:05,673 INFO kernel:[    0.000000] 	Offload RCU callbacks from all CPUs
>10:51:05,673 INFO kernel:[    0.000000] 	Offload RCU callbacks from CPUs: 0-7.
>10:51:05,673 INFO kernel:[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8
>10:51:05,673 INFO kernel:[    0.000000] NR_IRQS:512 nr_irqs:512 16
>10:51:05,673 DEBUG kernel:[    0.000000] pic: no ISA interrupt controller
>10:51:05,673 DEBUG kernel:[    0.000000] time_init: decrementer frequency = 512.000000 MHz
>10:51:05,673 DEBUG kernel:[    0.000000] time_init: processor frequency   = 4228.000000 MHz
>10:51:05,673 INFO kernel:[    0.000002] clocksource: timebase mult[1f40000] shift[24] registered
>10:51:05,673 DEBUG kernel:[    0.000603] clockevent: decrementer mult[83126e98] shift[32] cpu[0]
>10:51:05,673 INFO kernel:[    0.000675] Console: colour dummy device 80x25
>10:51:05,673 INFO kernel:[    0.001110] console [hvc0] enabled
>10:51:05,673 INFO kernel:[    0.001459] bootconsole [udbg0] disabled
>10:51:05,673 INFO kernel:[    0.004928] allocated 1048576 bytes of page_cgroup
>10:51:05,673 INFO kernel:[    0.005045] please try 'cgroup_disable=memory' option if you don't want memory cgroups
>10:51:05,673 INFO kernel:[    0.005138] mempolicy: Enabling automatic NUMA balancing. Configure with numa_balancing= or the kernel.numa_balancing sysctl
>10:51:05,673 INFO kernel:[    0.005219] pid_max: default: 32768 minimum: 301
>10:51:05,673 INFO kernel:[    0.005358] Security Framework initialized
>10:51:05,673 INFO kernel:[    0.005400] SELinux:  Initializing.
>10:51:05,673 DEBUG kernel:[    0.005434] SELinux:  Starting in permissive mode
>10:51:05,673 INFO kernel:[    0.005612] Dentry cache hash table entries: 524288 (order: 6, 4194304 bytes)
>10:51:05,673 INFO kernel:[    0.006814] Inode-cache hash table entries: 262144 (order: 5, 2097152 bytes)
>10:51:05,673 INFO kernel:[    0.007430] Mount-cache hash table entries: 8192 (order: 0, 65536 bytes)
>10:51:05,673 INFO kernel:[    0.007478] Mountpoint-cache hash table entries: 8192 (order: 0, 65536 bytes)
>10:51:05,673 INFO kernel:[    0.007930] Initializing cgroup subsys memory
>10:51:05,673 INFO kernel:[    0.008083] Initializing cgroup subsys devices
>10:51:05,673 INFO kernel:[    0.008123] Initializing cgroup subsys freezer
>10:51:05,673 INFO kernel:[    0.008159] Initializing cgroup subsys net_cls
>10:51:05,673 INFO kernel:[    0.008196] Initializing cgroup subsys blkio
>10:51:05,673 INFO kernel:[    0.008232] Initializing cgroup subsys perf_event
>10:51:05,673 INFO kernel:[    0.008268] Initializing cgroup subsys net_prio
>10:51:05,673 INFO kernel:[    0.008305] Initializing cgroup subsys hugetlb
>10:51:05,673 INFO kernel:[    0.008351] ftrace: allocating 24544 entries in 9 pages
>10:51:05,673 INFO kernel:[    0.017834] EEH: pSeries platform initialized
>10:51:05,673 INFO kernel:[    0.017886] POWER7 performance monitor hardware support registered
>10:51:05,673 INFO kernel:[    0.302873] Brought up 8 CPUs
>10:51:05,673 DEBUG kernel:[    0.303065] Node 0 CPUs: 0-7
>10:51:05,673 INFO kernel:[    0.303085] Enabling Asymmetric SMT scheduling
>10:51:05,673 INFO kernel:[    0.307296] devtmpfs: initialized
>10:51:05,673 INFO kernel:[    0.337157] EEH: devices created
>10:51:05,673 INFO kernel:[    0.338969] atomic64_test: passed
>10:51:05,673 INFO kernel:[    0.343051] NET: Registered protocol family 16
>10:51:05,673 WARNING kernel:[    0.343454] pseries_eeh_init: RTAS service <ibm,set-eeh-option> invalid
>10:51:05,673 WARNING kernel:[    0.343690] eeh_init: Failed to call platform init function (-22)
>10:51:05,673 INFO kernel:[    0.345090] IBM eBus Device Driver
>10:51:05,673 INFO kernel:[    0.349685] cpuidle: using governor menu
>10:51:05,673 INFO kernel:[    0.350053] pstore: Registered nvram as persistent store backend
>10:51:05,673 INFO kernel:[    0.353061] PCI: Probing PCI hardware
>10:51:05,673 ERR kernel:[    0.353269] no ibm,pcie-link-speed-stats property
>10:51:05,673 INFO kernel:[    0.354415] PCI host bridge to bus 0000:00
>10:51:05,673 INFO kernel:[    0.354617] pci_bus 0000:00: root bus resource [io  0x10000-0x1ffff] (bus address [0x0000-0xffff])
>10:51:05,673 INFO kernel:[    0.354928] pci_bus 0000:00: root bus resource [mem 0x100a0000000-0x100bfffffff] (bus address [0x80000000-0x9fffffff])
>10:51:05,673 INFO kernel:[    0.355285] pci_bus 0000:00: root bus resource [bus 00-ff]
>10:51:05,673 INFO kernel:[    0.383567] IOMMU table initialized, virtual merging enabled
>10:51:05,673 DEBUG kernel:[    0.383790] PCI: Probing PCI hardware done
>10:51:05,673 INFO kernel:[    0.406316] vgaarb: loaded
>10:51:05,673 NOTICE kernel:[    0.406474] SCSI subsystem initialized
>10:51:05,673 DEBUG kernel:[    0.406759] libata version 3.00 loaded.
>10:51:05,673 INFO kernel:[    0.406801] usbcore: registered new interface driver usbfs
>10:51:05,673 INFO kernel:[    0.407240] usbcore: registered new interface driver hub
>10:51:05,673 INFO kernel:[    0.407679] usbcore: registered new device driver usb
>10:51:05,673 INFO kernel:[    0.412606] NetLabel: Initializing
>10:51:05,673 INFO kernel:[    0.412759] NetLabel:  domain hash size = 128
>10:51:05,673 INFO kernel:[    0.412941] NetLabel:  protocols = UNLABELED CIPSOv4
>10:51:05,673 INFO kernel:[    0.413395] NetLabel:  unlabeled traffic allowed by default
>10:51:05,673 INFO kernel:[    0.414342] Switched to clocksource timebase
>10:51:05,673 INFO kernel:[    0.452024] NET: Registered protocol family 2
>10:51:05,673 INFO kernel:[    0.454448] TCP established hash table entries: 32768 (order: 2, 262144 bytes)
>10:51:05,673 INFO kernel:[    0.458127] TCP bind hash table entries: 32768 (order: 3, 524288 bytes)
>10:51:05,673 INFO kernel:[    0.462575] TCP: Hash tables configured (established 32768 bind 32768)
>10:51:05,673 INFO kernel:[    0.462812] TCP: reno registered
>10:51:05,673 INFO kernel:[    0.462986] UDP hash table entries: 2048 (order: 0, 65536 bytes)
>10:51:05,673 INFO kernel:[    0.463253] UDP-Lite hash table entries: 2048 (order: 0, 65536 bytes)
>10:51:05,673 INFO kernel:[    0.464368] NET: Registered protocol family 1
>10:51:05,673 DEBUG kernel:[    0.464695] PCI: CLS 0 bytes, default 128
>10:51:05,673 INFO kernel:[    0.465704] Unpacking initramfs...
>10:51:05,673 INFO kernel:[    4.846801] Freeing initrd memory: 37632K (c000000003a00000 - c000000005ec0000)
>10:51:05,673 INFO kernel:[    4.847690] rtasd: No event-scan on system
>10:51:05,673 INFO kernel:[    4.847803] rtas_flash: no firmware flash support
>10:51:05,673 INFO kernel:[    4.895167] futex hash table entries: 2048 (order: 2, 262144 bytes)
>10:51:05,673 INFO kernel:[    4.895446] audit: initializing netlink subsys (disabled)
>10:51:05,673 NOTICE kernel:[    4.895648] audit: type=2000 audit(1413197454.680:1): initialized
>10:51:05,673 INFO kernel:[    4.904061] zpool: loaded
>10:51:05,673 NOTICE kernel:[    4.904522] VFS: Disk quotas dquot_6.5.2
>10:51:05,673 WARNING kernel:[    4.904699] Dquot-cache hash table entries: 8192 (order 0, 65536 bytes)
>10:51:05,673 INFO kernel:[    4.905365] hugetlbfs: disabling because there are no supported hugepage sizes
>10:51:05,673 INFO kernel:[    4.905608] msgmni has been set to 8128
>10:51:05,673 NOTICE kernel:[    4.905866] Key type big_key registered
>10:51:05,673 DEBUG kernel:[    4.905950] SELinux:  Registering netfilter hooks
>10:51:05,673 INFO kernel:[    4.907567] alg: No test for stdrng (krng)
>10:51:05,673 INFO kernel:[    4.907682] NET: Registered protocol family 38
>10:51:05,673 INFO kernel:[    4.907846] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
>10:51:05,673 INFO kernel:[    4.908247] io scheduler noop registered
>10:51:05,673 INFO kernel:[    4.908400] io scheduler deadline registered
>10:51:05,673 INFO kernel:[    4.909898] io scheduler cfq registered (default)
>10:51:05,673 INFO kernel:[    4.910089] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
>10:51:05,673 INFO kernel:[    4.910643] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
>10:51:05,673 INFO kernel:[    4.911399] Linux agpgart interface v0.103
>10:51:05,673 INFO kernel:[    4.911574] Uniform Multi-Platform E-IDE driver
>10:51:05,673 INFO kernel:[    4.912437] ide-gd driver 1.18
>10:51:05,673 INFO kernel:[    4.912666] libphy: Fixed MDIO Bus: probed
>10:51:05,673 INFO kernel:[    4.912804] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
>10:51:05,673 INFO kernel:[    4.913178] ehci-pci: EHCI PCI platform driver
>10:51:05,673 INFO kernel:[    4.913316] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
>10:51:05,673 INFO kernel:[    4.913691] ohci-pci: OHCI PCI platform driver
>10:51:05,673 INFO kernel:[    4.914084] uhci_hcd: USB Universal Host Controller Interface driver
>10:51:05,673 INFO kernel:[    4.914691] usbcore: registered new interface driver usbserial
>10:51:05,673 INFO kernel:[    4.914829] usbcore: registered new interface driver usbserial_generic
>10:51:05,673 INFO kernel:[    4.914965] usbserial: USB Serial support registered for generic
>10:51:05,673 INFO kernel:[    4.915169] mousedev: PS/2 mouse device common for all mice
>10:51:05,673 INFO kernel:[    4.916383] rtc-generic rtc-generic: rtc core: registered rtc-generic as rtc0
>10:51:05,673 ERR kernel:[    4.917554] ibmpowernv ibmpowernv.0: Opal node 'sensors' not found
>10:51:05,673 ERR kernel:[    4.917898] ibmpowernv: Platfrom driver probe failed
>10:51:05,673 INFO kernel:[    4.920452] device-mapper: uevent: version 1.0.3
>10:51:05,673 INFO kernel:[    4.921093] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com
>10:51:05,673 DEBUG kernel:[    4.922148] pseries_idle_driver registered
>10:51:05,673 INFO kernel:[    4.922164] hidraw: raw HID events driver (C) Jiri Kosina
>10:51:05,673 INFO kernel:[    4.922396] usbcore: registered new interface driver usbhid
>10:51:05,673 INFO kernel:[    4.922504] usbhid: USB HID core driver
>10:51:05,673 INFO kernel:[    4.922651] drop_monitor: Initializing network drop monitor service
>10:51:05,673 INFO kernel:[    4.922852] ip_tables: (C) 2000-2006 Netfilter Core Team
>10:51:05,673 INFO kernel:[    4.923215] TCP: cubic registered
>10:51:05,673 INFO kernel:[    4.923391] Initializing XFRM netlink socket
>10:51:05,673 INFO kernel:[    4.927420] NET: Registered protocol family 10
>10:51:05,673 INFO kernel:[    4.927987] mip6: Mobile IPv6
>10:51:05,673 INFO kernel:[    4.928070] NET: Registered protocol family 17
>10:51:05,673 DEBUG kernel:[    4.928902] Running MSI bitmap self-tests ...
>10:51:05,673 INFO kernel:[    4.935038] registered taskstats version 1
>10:51:05,673 INFO kernel:[    4.935845] rtc-generic rtc-generic: setting system clock to 2014-10-13 10:50:55 UTC (1413197455)
>10:51:05,673 DEBUG kernel:[    4.936036] PM: Hibernation image not present or could not be loaded.
>10:51:05,673 INFO kernel:[    4.937992] Freeing unused kernel memory: 7232K (c000000000ca0000 - c0000000013b0000)
>10:51:05,673 NOTICE kernel:[    4.945468] random: systemd urandom read with 35 bits of entropy available
>10:51:05,673 INFO kernel:[    5.069959] device-mapper: multipath: version 1.7.0 loaded
>10:51:05,673 INFO kernel:[    5.401814] squashfs: version 4.0 (2009/01/31) Phillip Lougher
>10:51:05,673 INFO kernel:[    5.404771] Loading iSCSI transport class v2.0-870.
>10:51:05,673 NOTICE kernel:[    5.406566] iscsi: registered transport (tcp)
>10:51:05,673 INFO kernel:[    5.408672] alua: device handler registered
>10:51:05,673 INFO kernel:[    5.410370] emc: device handler registered
>10:51:05,673 INFO kernel:[    5.411918] hp_sw: device handler registered
>10:51:05,673 INFO kernel:[    5.413609] rdac: device handler registered
>10:51:05,673 INFO kernel:[    5.419488] md: raid0 personality registered for level 0
>10:51:05,673 INFO kernel:[    5.421427] md: raid1 personality registered for level 1
>10:51:05,673 WARNING kernel:[    5.584348] raid6: altivecx1  8989 MB/s
>10:51:05,673 WARNING kernel:[    5.754329] raid6: altivecx2 13612 MB/s
>10:51:05,673 WARNING kernel:[    5.924359] raid6: altivecx4 15020 MB/s
>10:51:05,673 WARNING kernel:[    6.094362] raid6: altivecx8 12103 MB/s
>10:51:05,673 WARNING kernel:[    6.264349] raid6: int64x1   4581 MB/s
>10:51:05,673 WARNING kernel:[    6.434343] raid6: int64x2   7428 MB/s
>10:51:05,673 WARNING kernel:[    6.604331] raid6: int64x4   8139 MB/s
>10:51:05,673 WARNING kernel:[    6.774340] raid6: int64x8   3228 MB/s
>10:51:05,673 WARNING kernel:[    6.774432] raid6: using algorithm altivecx4 (15020 MB/s)
>10:51:05,673 WARNING kernel:[    6.774528] raid6: using intx1 recovery algorithm
>10:51:05,673 INFO kernel:[    6.774789] async_tx: api initialized (async)
>10:51:05,673 INFO kernel:[    6.775216] xor: measuring software checksum speed
>10:51:05,673 INFO kernel:[    6.874366]    8regs     : 10457.600 MB/sec
>10:51:05,673 INFO kernel:[    6.974377]    8regs_prefetch: 11168.000 MB/sec
>10:51:05,673 INFO kernel:[    7.074376]    32regs    : 10400.000 MB/sec
>10:51:05,673 INFO kernel:[    7.174359]    32regs_prefetch: 10822.400 MB/sec
>10:51:05,673 INFO kernel:[    7.274359]    altivec   : 16492.800 MB/sec
>10:51:05,673 INFO kernel:[    7.274510] xor: using function: altivec (16492.800 MB/sec)
>10:51:05,673 INFO kernel:[    7.276500] md: raid6 personality registered for level 6
>10:51:05,673 INFO kernel:[    7.277039] md: raid5 personality registered for level 5
>10:51:05,673 INFO kernel:[    7.277208] md: raid4 personality registered for level 4
>10:51:05,673 INFO kernel:[    7.282172] md: raid10 personality registered for level 10
>10:51:05,673 INFO kernel:[    7.283899] md: linear personality registered for level -1
>10:51:05,673 INFO kernel:[    7.292328] device-mapper: multipath round-robin: version 1.0.0 loaded
>10:51:05,673 INFO kernel:[    7.315003] RPC: Registered named UNIX socket transport module.
>10:51:05,673 INFO kernel:[    7.315147] RPC: Registered udp transport module.
>10:51:05,673 INFO kernel:[    7.315252] RPC: Registered tcp transport module.
>10:51:05,673 INFO kernel:[    7.315358] RPC: Registered tcp NFSv4.1 backchannel transport module.
>10:51:05,673 DEBUG kernel:[    7.636403] ibmveth: IBM Power Virtual Ethernet Driver 1.04
>10:51:05,673 ERR kernel:[    7.642951] ibmveth 71000004 (unnamed net_device) (uninitialized): unable to change checksum offload settings. 1 rc=-2 ret_attr=71000004
>10:51:05,673 INFO kernel:[    7.644097] ibmvscsi 71000002: SRP_VERSION: 16.a
>10:51:05,673 INFO kernel:[    7.644354] scsi host0: IBM POWER Virtual SCSI Adapter 1.5.9
>10:51:05,673 INFO kernel:[    7.644809] ibmvscsi 71000002: partner initialization complete
>10:51:05,673 INFO kernel:[    7.644941] ibmvscsi 71000002: host srp version: 16.a, host partition qemu (0), OS 2, max io 2097152
>10:51:05,673 INFO kernel:[    7.645093] ibmvscsi 71000002: sent SRP login
>10:51:05,673 INFO kernel:[    7.645172] ibmvscsi 71000002: SRP_LOGIN succeeded
>10:51:05,673 INFO kernel:[    7.664694] ibmvscsi 71000003: SRP_VERSION: 16.a
>10:51:05,673 NOTICE kernel:[    7.664818] scsi 0:0:0:0: Direct-Access     QEMU     QEMU HARDDISK    2.1. PQ: 0 ANSI: 5
>10:51:05,673 INFO kernel:[    7.664990] scsi host1: IBM POWER Virtual SCSI Adapter 1.5.9
>10:51:05,673 INFO kernel:[    7.665481] ibmvscsi 71000003: partner initialization complete
>10:51:05,673 INFO kernel:[    7.665708] ibmvscsi 71000003: host srp version: 16.a, host partition qemu (0), OS 2, max io 2097152
>10:51:05,673 INFO kernel:[    7.665978] ibmvscsi 71000003: sent SRP login
>10:51:05,673 INFO kernel:[    7.666136] ibmvscsi 71000003: SRP_LOGIN succeeded
>10:51:05,673 NOTICE kernel:[    7.667805] scsi 0:0:2:0: CD-ROM            QEMU     QEMU CD-ROM      2.1. PQ: 0 ANSI: 5
>10:51:05,673 NOTICE kernel:[    7.905189] sd 0:0:0:0: Attached scsi generic sg0 type 0
>10:51:05,673 NOTICE kernel:[    7.905192] sd 0:0:0:0: [sda] 20971520 512-byte logical blocks: (10.7 GB/10.0 GiB)
>10:51:05,673 INFO kernel:[    7.906136] sr 0:0:2:0: [sr0] scsi3-mmc drive: 16x/50x cd/rw xa/form2 cdda tray
>10:51:05,673 INFO kernel:[    7.906366] cdrom: Uniform CD-ROM driver Revision: 3.20
>10:51:05,673 NOTICE kernel:[    7.906508] sd 0:0:0:0: [sda] Write Protect is off
>10:51:05,673 DEBUG kernel:[    7.906549] sd 0:0:0:0: [sda] Mode Sense: 63 00 00 08
>10:51:05,673 NOTICE kernel:[    7.906951] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
>10:51:05,673 DEBUG kernel:[    7.907217] sr 0:0:2:0: Attached scsi CD-ROM sr0
>10:51:05,673 NOTICE kernel:[    7.907597] sr 0:0:2:0: Attached scsi generic sg1 type 5
>10:51:05,673 INFO kernel:[    7.910704]  sda: sda1 sda2 sda3
>10:51:05,673 NOTICE kernel:[    7.914520] sd 0:0:0:0: [sda] Attached SCSI disk
>10:51:05,673 NOTICE kernel:[    7.929712] random: nonblocking pool is initialized
>10:51:05,673 DEBUG kernel:[    8.723001] ISO 9660 Extensions: Microsoft Joliet Level 3
>10:51:05,673 DEBUG kernel:[    8.725233] ISO 9660 Extensions: RRIP_1991A
>10:51:05,673 INFO kernel:[    8.797803] loop: module loaded
>10:51:05,673 INFO kernel:[    9.072245] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
>10:51:05,673 DEBUG kernel:[   10.177933] SELinux: 2048 avtab hash slots, 109098 rules.
>10:51:05,673 DEBUG kernel:[   10.193699] SELinux: 2048 avtab hash slots, 109098 rules.
>10:51:05,673 DEBUG kernel:[   10.211271] SELinux:  8 users, 103 roles, 4914 types, 292 bools, 1 sens, 1024 cats
>10:51:05,673 DEBUG kernel:[   10.211282] SELinux:  83 classes, 109098 rules
>10:51:05,673 INFO kernel:[   10.216051] SELinux:  Permission audit_read in class capability2 not defined in policy.
>10:51:05,673 INFO kernel:[   10.216419] SELinux: the above unknown classes and permissions will be allowed
>10:51:05,673 DEBUG kernel:[   10.216670] SELinux:  Completing initialization.
>10:51:05,673 DEBUG kernel:[   10.216671] SELinux:  Setting up existing superblocks.
>10:51:05,673 DEBUG kernel:[   10.216678] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.216701] SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.216706] SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.216712] SELinux: initialized (dev proc, type proc), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.216793] SELinux: initialized (dev devtmpfs, type devtmpfs), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.217244] SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
>10:51:05,673 DEBUG kernel:[   10.217257] SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.219240] SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
>10:51:05,673 DEBUG kernel:[   10.219244] SELinux: initialized (dev anon_inodefs, type anon_inodefs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.219248] SELinux: initialized (dev aio, type aio), not configured for labeling
>10:51:05,673 DEBUG kernel:[   10.219252] SELinux: initialized (dev devpts, type devpts), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.219279] SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.219285] SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.219332] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221090] SELinux: initialized (dev securityfs, type securityfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221094] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.221099] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.221200] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>10:51:05,673 DEBUG kernel:[   10.221228] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221236] SELinux: initialized (dev pstore, type pstore), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221239] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221241] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221244] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221267] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221275] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221278] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221280] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221302] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221305] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221317] SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221335] SELinux: initialized (dev configfs, type configfs), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221339] SELinux: initialized (dev sr0, type iso9660), uses genfs_contexts
>10:51:05,673 DEBUG kernel:[   10.221357] SELinux: initialized (dev loop0, type squashfs), uses xattr
>10:51:05,673 DEBUG kernel:[   10.221382] SELinux: initialized (dev dm-0, type ext4), uses xattr
>10:51:05,673 NOTICE kernel:[   10.236691] audit: type=1403 audit(1413197460.790:2): policy loaded auid=4294967295 ses=4294967295
>10:51:05,673 DEBUG kernel:[   10.661534] SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
>10:51:05,673 NOTICE kernel:[   10.774722] audit: type=1400 audit(1413197461.330:3): avc:  denied  { read } for  pid=875 comm="systemd-journal" name="machine-id" dev="dm-0" ino=51126 scontext=system_u:system_r:syslogd_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=file permissive=1
>10:51:05,673 NOTICE kernel:[   10.774738] audit: type=1400 audit(1413197461.330:4): avc:  denied  { open } for  pid=875 comm="systemd-journal" path="/etc/machine-id" dev="dm-0" ino=51126 scontext=system_u:system_r:syslogd_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=file permissive=1
>10:51:05,673 DEBUG kernel:[   10.811287] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
>10:51:05,673 NOTICE kernel:[   15.107734] audit: type=1400 audit(1413197465.660:5): avc:  denied  { unlink } for  pid=1454 comm="rsyslogd" name="log" dev="devtmpfs" ino=9332 scontext=system_u:system_r:syslogd_t:s0 tcontext=system_u:object_r:device_t:s0 tclass=lnk_file permissive=1
>10:51:06,010 INFO NetworkManager: <info> NetworkManager (version 0.9.10.0-5.git20140704.fc21) is starting...
>10:51:06,010 INFO NetworkManager: <info> Read config: /etc/NetworkManager/NetworkManager.conf
>10:51:06,010 INFO NetworkManager: <info> WEXT support is enabled
>10:51:06,138 INFO kernel:[   15.564648] cfg80211: Calling CRDA to update world regulatory domain
>10:51:06,288 NOTICE kernel:[   15.717675] audit: type=1400 audit(1413197466.270:6): avc:  denied  { read } for  pid=1455 comm="NetworkManager" name="VPN" dev="dm-0" ino=111266 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=dir permissive=1
>10:51:06,288 NOTICE kernel:[   15.723622] audit: type=1400 audit(1413197466.270:7): avc:  denied  { execute } for  pid=1455 comm="NetworkManager" name="20-chrony" dev="dm-0" ino=111270 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=file permissive=1
>10:51:06,295 NOTICE dbus: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service'
>10:51:06,354 INFO polkitd: Started polkitd version 0.112
>10:51:06,440 NOTICE dbus: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
>10:51:06,452 WARNING NetworkManager: <warn> Could not get hostname: failed to read /etc/sysconfig/network
>10:51:06,452 INFO NetworkManager: <info> Acquired D-Bus service com.redhat.ifcfgrh1
>10:51:06,452 INFO NetworkManager: <info> Loaded plugin ifcfg-rh: (c) 2007 - 2013 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>10:51:06,452 INFO NetworkManager: <info> Loaded plugin keyfile: (c) 2007 - 2013 Red Hat, Inc.  To report bugs please use the NetworkManager mailing list.
>10:51:06,495 INFO NetworkManager: <info> parsing /etc/sysconfig/network-scripts/ifcfg-lo ... 
>10:51:06,497 NOTICE dbus: [system] Activating via systemd: service name='org.freedesktop.login1' unit='dbus-org.freedesktop.login1.service'
>10:51:06,534 NOTICE dbus: [system] Successfully activated service 'org.freedesktop.login1'
>10:51:06,538 INFO NetworkManager: <info> monitoring kernel firmware directory '/lib/firmware'.
>10:51:06,548 NOTICE kernel:[   15.976132] audit: type=1400 audit(1413197466.530:8): avc:  denied  { write } for  pid=1481 comm="dbus-daemon" path="/run/systemd/inhibit/1.ref" dev="tmpfs" ino=14732 scontext=system_u:system_r:system_dbusd_t:s0-s0:c0.c1023 tcontext=system_u:object_r:init_var_run_t:s0 tclass=fifo_file permissive=1
>10:51:06,548 NOTICE kernel:[   15.976344] audit: type=1400 audit(1413197466.530:9): avc:  denied  { write } for  pid=1484 comm="gdbus" path="/run/systemd/inhibit/1.ref" dev="tmpfs" ino=14732 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=system_u:object_r:init_var_run_t:s0 tclass=fifo_file permissive=1
>10:51:06,549 INFO NetworkManager: <info> WiFi hardware radio set enabled
>10:51:06,549 INFO NetworkManager: <info> WWAN hardware radio set enabled
>10:51:06,564 INFO NetworkManager: <info> Loaded device plugin: /usr/lib64/NetworkManager/libnm-device-plugin-wifi.so
>10:51:06,565 INFO NetworkManager: <info> WiFi enabled by radio killswitch; enabled by state file
>10:51:06,565 INFO NetworkManager: <info> WWAN enabled by radio killswitch; enabled by state file
>10:51:06,565 INFO NetworkManager: <info> WiMAX enabled by radio killswitch; enabled by state file
>10:51:06,565 INFO NetworkManager: <info> Networking is enabled by state file
>10:51:06,709 INFO NetworkManager: <info> (lo): link connected
>10:51:06,709 INFO NetworkManager: <info> (lo): carrier is ON
>10:51:06,709 INFO NetworkManager: <info> (lo): new Generic device (driver: 'unknown' ifindex: 1)
>10:51:06,709 INFO NetworkManager: <info> (lo): exported as /org/freedesktop/NetworkManager/Devices/0
>10:51:06,918 INFO NetworkManager: <info> (eth0): carrier is OFF
>10:51:07,028 INFO NetworkManager: <info> (eth0): new Ethernet device (driver: 'ibmveth' ifindex: 2)
>10:51:07,028 INFO NetworkManager: <info> (eth0): exported as /org/freedesktop/NetworkManager/Devices/1
>10:51:07,029 INFO NetworkManager: <info> (eth0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
>10:51:07,108 INFO NetworkManager: <info> (eth0): link connected
>10:51:07,108 INFO NetworkManager: <info> (eth0): preparing device
>10:51:07,111 INFO NetworkManager: <info>     read connection 'Wired connection 1'
>10:51:07,111 INFO NetworkManager: <info> (eth0): created default wired connection 'Wired connection 1'
>10:51:07,113 INFO NetworkManager: <info> (eth0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
>10:51:07,113 INFO NetworkManager: <info> Auto-activating connection 'Wired connection 1'.
>10:51:07,114 INFO NetworkManager: <info> Activation (eth0) starting connection 'Wired connection 1'
>10:51:07,114 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) scheduled...
>10:51:07,114 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) started...
>10:51:07,114 INFO NetworkManager: <info> (eth0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
>10:51:07,114 INFO NetworkManager: <info> NetworkManager state is now CONNECTING
>10:51:07,114 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) scheduled...
>10:51:07,114 INFO NetworkManager: <info> Activation (eth0) Stage 1 of 5 (Device Prepare) complete.
>10:51:07,115 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) starting...
>10:51:07,115 INFO NetworkManager: <info> (eth0): device state change: prepare -> config (reason 'none') [40 50 0]
>10:51:07,188 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) successful.
>10:51:07,188 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) scheduled.
>10:51:07,188 INFO NetworkManager: <info> Activation (eth0) Stage 2 of 5 (Device Configure) complete.
>10:51:07,188 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) started...
>10:51:07,188 INFO NetworkManager: <info> (eth0): device state change: config -> ip-config (reason 'none') [50 70 0]
>10:51:07,190 INFO NetworkManager: <info> Activation (eth0) Beginning DHCPv4 transaction (timeout in 45 seconds)
>10:51:07,217 INFO NetworkManager: <info> dhclient started with pid 1517
>10:51:07,218 INFO NetworkManager: <info> Activation (eth0) Stage 3 of 5 (IP Configure Start) complete.
>10:51:07,350 INFO dhclient: Internet Systems Consortium DHCP Client 4.3.1
>10:51:07,351 INFO dhclient: Copyright 2004-2014 Internet Systems Consortium.
>10:51:07,351 INFO dhclient: All rights reserved.
>10:51:07,351 INFO dhclient: For info, please visit https://www.isc.org/software/dhcp/
>10:51:07,351 INFO dhclient: 
>10:51:07,402 INFO NetworkManager: <info> (eth0): DHCPv4 state changed nbi -> preinit
>10:51:07,608 INFO dhclient: Listening on LPF/eth0/7e:ad:cd:ad:de:b8
>10:51:07,608 INFO dhclient: Sending on   LPF/eth0/7e:ad:cd:ad:de:b8
>10:51:07,608 INFO dhclient: Sending on   Socket/fallback
>10:51:07,608 INFO dhclient: Created duid \000\001\000\001\033\316k\033~\255\315\255\336\270.
>10:51:07,609 INFO dhclient: DHCPDISCOVER on eth0 to 255.255.255.255 port 67 interval 8 (xid=0x37f2794a)
>10:51:07,938 NOTICE kernel:[   17.371529] audit: type=1400 audit(1413197467.920:10): avc:  denied  { read write } for  pid=1520 comm="ldconfig" path="/dev/pts/0" dev="devpts" ino=3 scontext=system_u:system_r:ldconfig_t:s0 tcontext=system_u:object_r:devpts_t:s0 tclass=chr_file permissive=1
>10:51:08,009 INFO dhclient: DHCPREQUEST on eth0 to 255.255.255.255 port 67 (xid=0x37f2794a)
>10:51:08,009 INFO dhclient: DHCPOFFER from 10.34.74.1
>10:51:08,056 INFO dhclient: DHCPACK from 10.34.74.1 (xid=0x37f2794a)
>10:51:08,066 INFO NetworkManager: <info> (eth0): DHCPv4 state changed preinit -> bound
>10:51:08,066 INFO NetworkManager: <info>   address 10.34.75.211
>10:51:08,066 INFO NetworkManager: <info>   plen 23 (255.255.254.0)
>10:51:08,066 INFO NetworkManager: <info>   gateway 10.34.75.254
>10:51:08,066 INFO NetworkManager: <info>   server identifier 10.34.74.1
>10:51:08,066 INFO NetworkManager: <info>   lease time 25200
>10:51:08,066 INFO NetworkManager: <info>   nameserver '10.34.32.1'
>10:51:08,066 INFO NetworkManager: <info>   nameserver '10.32.63.5'
>10:51:08,066 INFO NetworkManager: <info>   nameserver '10.34.255.7'
>10:51:08,066 INFO NetworkManager: <info>   nameserver '10.5.30.160'
>10:51:08,066 INFO NetworkManager: <info>   domain name 'lab.eng.brq.redhat.com'
>10:51:08,066 INFO NetworkManager: <info>   domain name 'englab.brq.redhat.com'
>10:51:08,066 INFO NetworkManager: <info>   domain name 'brq.redhat.com'
>10:51:08,066 INFO NetworkManager: <info>   domain name 'redhat.com'
>10:51:08,067 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Configure Commit) scheduled...
>10:51:08,068 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) started...
>10:51:08,068 INFO NetworkManager: <info> (eth0): device state change: ip-config -> ip-check (reason 'none') [70 80 0]
>10:51:08,069 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv4 Commit) complete.
>10:51:08,070 INFO NetworkManager: <info> (eth0): device state change: ip-check -> secondaries (reason 'none') [80 90 0]
>10:51:08,070 INFO NetworkManager: <info> (eth0): device state change: secondaries -> activated (reason 'none') [90 100 0]
>10:51:08,070 INFO NetworkManager: <info> NetworkManager state is now CONNECTED_LOCAL
>10:51:08,071 INFO NetworkManager: <info> NetworkManager state is now CONNECTED_GLOBAL
>10:51:08,071 INFO NetworkManager: <info> Policy set 'Wired connection 1' (eth0) as default for IPv4 routing and DNS.
>10:51:08,074 INFO NetworkManager: <info> Activation (eth0) successful, device activated.
>10:51:08,076 NOTICE dbus: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service'
>10:51:08,093 INFO dhclient: bound to 10.34.75.211 -- renewal in 9615 seconds.
>10:51:08,104 NOTICE dbus: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
>10:51:08,105 NOTICE nm-dispatcher: Dispatching action 'up' for eth0
>10:51:08,108 NOTICE kernel:[   17.542466] audit: type=1400 audit(1413197468.090:11): avc:  denied  { execute_no_trans } for  pid=1526 comm="nm-dispatcher" path="/etc/NetworkManager/dispatcher.d/00-netreport" dev="dm-0" ino=111272 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=file permissive=1
>10:51:08,148 NOTICE kernel:[   17.581441] audit: type=1107 audit(1413197468.130:12): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='avc:  denied  { reload } for auid=0 uid=0 gid=0 path="/usr/lib/systemd/system/iscsi.service" cmdline="/bin/systemctl --no-block reload iscsi.service" scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=service
>10:51:08,148 NOTICE kernel:[   17.581441]  exe="/usr/lib/systemd/systemd" sauid=0 hostname=? addr=? terminal=?'
>10:51:08,148 NOTICE kernel:[   17.581777] audit: type=1107 audit(1413197468.130:13): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='avc:  denied  { status } for auid=0 uid=0 gid=0 path="/usr/lib/systemd/system/iscsi.service" cmdline="/bin/systemctl --no-block reload iscsi.service" scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:unlabeled_t:s0 tclass=service
>10:51:08,148 NOTICE kernel:[   17.581777]  exe="/usr/lib/systemd/systemd" sauid=0 hostname=? addr=? terminal=?'
>10:51:08,172 INFO NetworkManager: <info> Setting system hostname to 'dhcp-75-211.lab.eng.brq.redhat.com' (from address lookup)
>10:51:08,178 NOTICE kernel:[   17.611665] audit: type=1400 audit(1413197468.160:14): avc:  denied  { add_name } for  pid=1533 comm="11-dhclient" name="chrony.servers.eth0" scontext=system_u:system_r:NetworkManager_t:s0 tcontext=system_u:object_r:dhcpc_state_t:s0 tclass=dir permissive=1
>10:51:08,178 NOTICE kernel:[   17.611707] audit: type=1400 audit(1413197468.160:15): avc:  denied  { create } for  pid=1533 comm="11-dhclient" name="chrony.servers.eth0" scontext=system_u:system_r:NetworkManager_t:s0 tcontext=system_u:object_r:dhcpc_state_t:s0 tclass=file permissive=1
>10:51:08,178 NOTICE kernel:[   17.611753] audit: type=1400 audit(1413197468.160:16): avc:  denied  { append } for  pid=1533 comm="11-dhclient" path="/var/lib/dhclient/chrony.servers.eth0" dev="dm-0" ino=21446 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=system_u:object_r:dhcpc_state_t:s0 tclass=file permissive=1
>10:51:08,208 NOTICE kernel:[   17.643495] audit: type=1400 audit(1413197468.190:17): avc:  denied  { read } for  pid=1540 comm="chronyc" name="chrony.keys" dev="dm-0" ino=51046 scontext=system_u:system_r:NetworkManager_t:s0 tcontext=unconfined_u:object_r:chronyd_keys_t:s0 tclass=file permissive=1
>10:51:08,310 NOTICE nm-dispatcher: Dispatching action 'hostname'
>10:51:09,251 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv6 Commit) scheduled...
>10:51:09,252 INFO NetworkManager: <info> Policy set 'Wired connection 1' (eth0) as default for IPv6 routing and DNS.
>10:51:09,252 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv6 Commit) started...
>10:51:09,253 INFO NetworkManager: <info> Activation (eth0) Stage 5 of 5 (IPv6 Commit) complete.
>10:51:12,779 INFO NetworkManager: <info> startup complete

Actions: View

Attachments on bug 1152014: 946319 | 946320 | 946321 | 946322 | 946323 | 946324 | 946325 | 946326 | 946327