Revision history [back]

click to hide/show revision 1
initial version

Job for openstack-nova-compute.service failed

I am a fresh to study openstack .I follow the steps in http://docs.openstack.org/juno/install-guide/install/yum/content/index.html in virtualbox,and when excuting the command in compute1 node "systemctl start openstack-nova-compute.service" ,i get "Job for openstack-nova-compute.service failed. See 'systemctl status openstack-nova-compute.service' and 'journalctl -xn' for details.".

so i paste these information i got below:
In Controller:

[root@localhost ~]# nova service-list
+----+------------------+-----------------------+----------+---------+-------+----------------------------+-----------------+
| Id | Binary | Host | Zone | Status | State | Updated_at | Disabled Reason |
+----+------------------+-----------------------+----------+---------+-------+----------------------------+-----------------+
| 1 | nova-conductor | localhost.localdomain | internal | enabled | up | 2014-11-18T07:01:27.000000 | -
| | 2 | nova-consoleauth | localhost.localdomain | internal | enabled | up | 2014-11-18T07:01:27.000000 | -
| | 3 | nova-scheduler | localhost.localdomain | internal | enabled | up | 2014-11-18T07:01:26.000000 | -
| | 4 | nova-cert | localhost.localdomain | internal | enabled | up | 2014-11-18T07:01:28.000000 | - | +----+------------------+-----------------------+----------+---------+-------+----------------------------+-----------------+

[root@localhost ~]# tail /var/log/rabbitmq/rabbit@localhost.log -n 30
=WARNING REPORT==== 18-Nov-2014::15:00:18 ===
closing AMQP connection <0.4626.0> (10.0.0.31:45219 -> 10.0.0.11:5672):
connection_closed_abruptly
=INFO REPORT==== 18-Nov-2014::15:00:18 ===
accepting AMQP connection <0.4654.0> (10.0.0.31:45221 -> 10.0.0.11:5672)
=INFO REPORT==== 18-Nov-2014::15:00:18 ===
accepting AMQP connection <0.4664.0> (10.0.0.31:45222 -> 10.0.0.11:5672)
=WARNING REPORT==== 18-Nov-2014::15:01:48 ===
closing AMQP connection <0.4654.0> (10.0.0.31:45221 -> 10.0.0.11:5672):
connection_closed_abruptly
=INFO REPORT==== 18-Nov-2014::15:01:48 ===
accepting AMQP connection <0.4683.0> (10.0.0.31:45223 -> 10.0.0.11:5672)
=INFO REPORT==== 18-Nov-2014::15:01:48 ===
accepting AMQP connection <0.4693.0> (10.0.0.31:45224 -> 10.0.0.11:5672)
=WARNING REPORT==== 18-Nov-2014::15:03:18 ===
closing AMQP connection <0.4683.0> (10.0.0.31:45223 -> 10.0.0.11:5672):
connection_closed_abruptly
=INFO REPORT==== 18-Nov-2014::15:03:19 ===
accepting AMQP connection <0.4711.0> (10.0.0.31:45225 -> 10.0.0.11:5672)
=INFO REPORT==== 18-Nov-2014::15:03:19 ===
accepting AMQP connection <0.4721.0> (10.0.0.31:45226 -> 10.0.0.11:5672)

In Compute1:

[root@localhost ~]# systemctl start openstack-nova-compute.service
Job for openstack-nova-compute.service failed. See 'systemctl status openstack-nova-compute.service' and 'journalctl -xn' for details.
[root@localhost ~]# systemctl status openstack-nova-compute.service
openstack-nova-compute.service - OpenStack Nova Compute Server
Loaded: loaded (/usr/lib/systemd/system/openstack-nova-compute.service; enabled)
Active: activating (start) since Tue 2014-11-18 14:37:58 CST; 11s ago
Main PID: 10244 (nova-compute)
CGroup: /system.slice/openstack-nova-compute.service
└─10244 /usr/bin/python /usr/bin/nova-compute
Nov 18 14:37:58 localhost.localdomain systemd[1]: openstack-nova-compute.service holdoff time over, scheduling restart.
Nov 18 14:37:58 localhost.localdomain systemd[1]: Stopping OpenStack Nova Compute Server...
Nov 18 14:37:58 localhost.localdomain systemd[1]: Starting OpenStack Nova Compute Server...

[root@localhost ~]# journalctl -xn
-- Logs begin at Tue 2014-11-18 11:06:02 CST, end at Tue 2014-11-18 14:37:58 CST. --
Nov 18 14:37:31 localhost.localdomain systemd[1]: Starting Session 5 of user root.
-- Subject: Unit session-5.scope has begun with start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit session-5.scope has begun starting up.
Nov 18 14:37:31 localhost.localdomain systemd[1]: Started Session 5 of user root.
-- Subject: Unit session-5.scope has finished start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit session-5.scope has finished starting up.
--
-- The start-up result is done.
Nov 18 14:37:31 localhost.localdomain systemd-logind[503]: New session 5 of user root.
-- Subject: A new session 5 has been created for user root
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- Documentation: http://www.freedesktop.org/wiki/Software/systemd/multiseat
--
-- A new session with the ID 5 has been created for the user root.
--
-- The leading process of the session is 10207.
Nov 18 14:37:31 localhost.localdomain sshd[10207]: pam_unix(sshd:session): session opened for user root by (uid=0)
Nov 18 14:37:58 localhost.localdomain systemd[1]: openstack-nova-compute.service operation timed out.
Terminating.
Nov 18 14:37:58 localhost.localdomain systemd[1]: Failed to start OpenStack Nova Compute Server.
-- Subject: Unit openstack-nova-compute.service has failed
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit openstack-nova-compute.service has failed.
--
-- The result is failed.
Nov 18 14:37:58 localhost.localdomain systemd[1]: Unit openstack-nova-compute.service entered failed state.
Nov 18 14:37:58 localhost.localdomain systemd[1]: openstack-nova-compute.service holdoff time over, scheduling restart.
Nov 18 14:37:58 localhost.localdomain systemd[1]: Stopping OpenStack Nova Compute Server...
-- Subject: Unit openstack-nova-compute.service has begun shutting down
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit openstack-nova-compute.service has begun shutting down.
Nov 18 14:37:58 localhost.localdomain systemd[1]: Starting OpenStack Nova Compute Server...
-- Subject: Unit openstack-nova-compute.service has begun with start-up
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
--
-- Unit openstack-nova-compute.service has begun starting up.

[root@localhost ~]# tail /var/log/nova/nova-compute.log -n 30
2014-11-18 15:01:48.903 10603 INFO nova.virt.driver [-] Loading compute driver 'libvirt.LibvirtDriver'
2014-11-18 15:01:48.909 10603 INFO nova.openstack.common.periodic_task [-] Skipping periodic task
_periodic_update_dns because its interval is negative
2014-11-18 15:01:48.948 10603 INFO oslo.messaging._drivers.impl_rabbit [req-9117355a-1758-437e-8131-937e287c51dd ] Connecting to AMQP server on controller:5672
2014-11-18 15:01:48.964 10603 INFO oslo.messaging._drivers.impl_rabbit [req-9117355a-1758-437e-8131-937e287c51dd ] Connected to AMQP server on controller:5672
2014-11-18 15:01:48.967 10603 INFO oslo.messaging._drivers.impl_rabbit [req-9117355a-1758-437e-8131-937e287c51dd ] Connecting to AMQP server on controller:5672
2014-11-18 15:01:48.977 10603 INFO oslo.messaging._drivers.impl_rabbit [req-9117355a-1758-437e-8131-937e287c51dd ] Connected to AMQP server on controller:5672
2014-11-18 15:03:19.026 10620 INFO nova.virt.driver [-] Loading compute driver 'libvirt.LibvirtDriver'
2014-11-18 15:03:19.031 10620 INFO nova.openstack.common.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
2014-11-18 15:03:19.068 10620 INFO oslo.messaging._drivers.impl_rabbit [req-a995e979-5890-4bed-8f80-b3fbeadcf958 ] Connecting to AMQP server on controller:5672
2014-11-18 15:03:19.085 10620 INFO oslo.messaging._drivers.impl_rabbit [req-a995e979-5890-4bed-8f80-b3fbeadcf958 ] Connected to AMQP server on controller:5672
2014-11-18 15:03:19.088 10620 INFO oslo.messaging._drivers.impl_rabbit [req-a995e979-5890-4bed-8f80-b3fbeadcf958 ] Connecting to AMQP server on controller:5672
2014-11-18 15:03:19.098 10620 INFO oslo.messaging._drivers.impl_rabbit [req-a995e979-5890-4bed-8f80-b3fbeadcf958 ] Connected to AMQP server on controller:5672
2014-11-18 15:04:49.151 10644 INFO nova.virt.driver [-] Loading compute driver 'libvirt.LibvirtDriver'
2014-11-18 15:04:49.156 10644 INFO nova.openstack.common.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
2014-11-18 15:04:49.196 10644 INFO oslo.messaging._drivers.impl_rabbit [req-d217d4ed-e462-4af6-9e6a-ba76892f1789 ] Connecting to AMQP server on controller:5672
2014-11-18 15:04:49.213 10644 INFO oslo.messaging._drivers.impl_rabbit [req-d217d4ed-e462-4af6-9e6a-ba76892f1789 ] Connected to AMQP server on controller:5672
2014-11-18 15:04:49.216 10644 INFO oslo.messaging._drivers.impl_rabbit [req-d217d4ed-e462-4af6-9e6a-ba76892f1789 ] Connecting to AMQP server on controller:5672
2014-11-18 15:04:49.227 10644 INFO oslo.messaging._drivers.impl_rabbit [req-d217d4ed-e462-4af6-9e6a-ba76892f1789 ] Connected to AMQP server on controller:5672
2014-11-18 15:06:19.227 10673 INFO nova.virt.driver [-] Loading compute driver 'libvirt.LibvirtDriver'
2014-11-18 15:06:19.232 10673 INFO nova.openstack.common.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
2014-11-18 15:06:19.271 10673 INFO oslo.messaging._drivers.impl_rabbit [req-4d9aa2f3-945b-4baa-96ed-5a11fe03306c ] Connecting to AMQP server on controller:5672
2014-11-18 15:06:19.287 10673 INFO oslo.messaging._drivers.impl_rabbit [req-4d9aa2f3-945b-4baa-96ed-5a11fe03306c ] Connected to AMQP server on controller:5672
2014-11-18 15:06:19.290 10673 INFO oslo.messaging._drivers.impl_rabbit [req-4d9aa2f3-945b-4baa-96ed-5a11fe03306c ] Connecting to AMQP server on controller:5672
2014-11-18 15:06:19.300 10673 INFO oslo.messaging._drivers.impl_rabbit [req-4d9aa2f3-945b-4baa-96ed-5a11fe03306c ] Connected to AMQP server on controller:5672
2014-11-18 15:07:49.345 10747 INFO nova.virt.driver [-] Loading compute driver 'libvirt.LibvirtDriver'
2014-11-18 15:07:49.350 10747 INFO nova.openstack.common.periodic_task [-] Skipping periodic task _periodic_update_dns because its interval is negative
2014-11-18 15:07:49.388 10747 INFO oslo.messaging._drivers.impl_rabbit [req-a552f90d-6319-4de2-b783-f55bcc959302 ] Connecting to AMQP server on controller:5672
2014-11-18 15:07:49.404 10747 INFO oslo.messaging._drivers.impl_rabbit [req-a552f90d-6319-4de2-b783-f55bcc959302 ] Connected to AMQP server on controller:5672
2014-11-18 15:07:49.408 10747 INFO oslo.messaging._drivers.impl_rabbit [req-a552f90d-6319-4de2-b783-f55bcc959302 ] Connecting to AMQP server on controller:5672
2014-11-18 15:07:49.418 10747 INFO oslo.messaging._drivers.impl_rabbit [req-a552f90d-6319-4de2-b783-f55bcc959302 ] Connected to AMQP server on controller:5672

I did't get any helpful information.