Hi all,
I found exception message in xcp-xapi.log
[20151124T09:22:36.680Z|debug|devcloud|460 INET 127.0.0.1:80|host.call_plugin
R:0bff3a505aba|dispatcher] Server_helpers.exec exception_handler: Got
exception XENAPI_PLUGIN_FAILURE: [ non-zero exit; ; Traceback (most recent
call last):
File "/usr/lib/xcp/plugins/vmops", line 39, in <module>
import cloudstack_pluginlib as lib
File "/etc/xapi.d/plugins/cloudstack_pluginlib.py", line 24, in <module>
import simplejson as json
ImportError: No module named simplejson
]
I added sys.path.append("/etc/xapi.d/plugins/") into
/usr/lib/xcp/plugins/vmops and installed simplejson.
Exception disappeared, but there was an error of another kind.
This is fragment of xcp-xapi.log
Do you have idea, what could be wrong?
2015-11-18 9:39 GMT+06:00 Abhinandan Prateek <
abhinandan.prat...@shapeblue.com>:
>
> The wiki below is much more elaborate on cloudstack setup using virtual
> box, and good for anyone wishing to be a cloudstack developer.
> http://www.shapeblue.com/virtualbox-test-env/
>
>
>
> On 17/11/15, 2:17 PM, "Илья Толстихин" <inosi...@gmail.com> wrote:
>
> >I copied vhd-util from /usr/bin/ on devcloud to
> >scripts/vm/hypervisor/xenserver of cloudstack.
> >Nothing changed.
> >
> >Also I tried to setup with manuals
> >
> https://github.com/apache/cloudstack/blob/master/tools/devcloud4/README.md
> >
> https://github.com/apache/cloudstack/blob/master/tools/devcloud4/advanced/README.md
> >
> >That gives the same error.
> >Do you have any other ideas, what could be the reason?
> >
> >2015-11-16 17:14 GMT+06:00 Илья Толстихин <inosi...@gmail.com>:
> >
> >> Thanks.
> >>
> >> 2015-11-16 10:28 GMT+06:00 Rajani Karuturi <raj...@apache.org>:
> >>
> >>> I find it easier to use devcloud4[1]. It the easiest way for xen6.2
> >>> devcloud setup.
> >>> The documentation is also good. Just install the required tools/IP
> configs
> >>> mentioned in README [2]. Then, go to the advanced and follow the
> readme[3]
> >>> there for a advanced zone setup.
> >>>
> >>> [1] https://github.com/apache/cloudstack/tree/master/tools/devcloud4
> >>> [2]
> >>>
> https://github.com/apache/cloudstack/blob/master/tools/devcloud4/README.md
> >>> [3]
> >>>
> >>>
> https://github.com/apache/cloudstack/blob/master/tools/devcloud4/advanced/README.md
> >>>
> >>> ~Rajani
> >>>
> >>> On Fri, Nov 13, 2015 at 12:52 PM, Daan Hoogland <
> daan.hoogl...@gmail.com>
> >>> wrote:
> >>>
> >>> > From your log it seems the host can be reached but doesn't respond s
> >>> > expected. First guess :wrong version of vhd-utils?
> >>> >
> >>> > Sorry for the late reaction. Stuck in traffic
> >>> >
> >>> > Biligual auto correct use. Read at your own risico
> >>> > Op 12-nov.-2015 10:28 schreef "Илья Толстихин" <inosi...@gmail.com>:
> >>> >
> >>> > > This is fragment of vmops.log file of MS.
> >>> > >
> >>> > > 2015-11-12 0:49 GMT+06:00 Daan Hoogland <daan.hoogl...@gmail.com>:
> >>> > >
> >>> > >> Ilya, Can you share the log of your management server? There must
> be
> >>> > some
> >>> > >> more info in there. And maybe you can check SRLog on the host To
> see
> >>> if
> >>> > >> anything happened there at all.
> >>> > >>
> >>> > >> On Wed, Nov 11, 2015 at 6:43 PM, Илья Толстихин <
> inosi...@gmail.com>
> >>> > >> wrote:
> >>> > >>
> >>> > >> > Yes, this is Xen.
> >>> > >> >
> >>> > >> > 2015-11-11 22:00 GMT+06:00 Daan Hoogland <
> daan.hoogl...@gmail.com
> >>> >:
> >>> > >> >
> >>> > >> > > this is a xen host, right?
> >>> > >> > >
> >>> > >> > > On Wed, Nov 11, 2015 at 3:43 PM, Илья Толстихин <
> >>> inosi...@gmail.com
> >>> > >
> >>> > >> > > wrote:
> >>> > >> > >
> >>> > >> > > > On host ssh is up with 22 port only.
> >>> > >> > > >
> >>> > >> > > > 2015-11-11 20:03 GMT+06:00 Илья Толстихин <
> inosi...@gmail.com
> >>> >:
> >>> > >> > > >
> >>> > >> > > > > Cloudstack 4.6.0
> >>> > >> > > > > Yes, I can ssh to host
> >>> > >> > > > >
> >>> > >> > > > > 2015-11-11 19:41 GMT+06:00 Daan Hoogland <
> >>> > daan.hoogl...@gmail.com
> >>> > >> >:
> >>> > >> > > > >
> >>> > >> > > > >> what version of ACS?
> >>> > >> > > > >> It sounds like a network issue between ms and host? can
> you
> >>> ssh
> >>> > >> from
> >>> > >> > > ms
> >>> > >> > > > to
> >>> > >> > > > >> host on the right port? (i think it was 3922, might be
> 3022)
> >>> > >> > > > >>
> >>> > >> > > > >> On Wed, Nov 11, 2015 at 1:42 PM, Илья Толстихин <
> >>> > >> inosi...@gmail.com
> >>> > >> > >
> >>> > >> > > > >> wrote:
> >>> > >> > > > >>
> >>> > >> > > > >> > Hi all,
> >>> > >> > > > >> > Could you please help with the DevCloud deployment.
> >>> > >> > > > >> >
> >>> > >> > > > >> > I imported devcloud into virtualbox, in settings
> *enabled
> >>> > PAE*
> >>> > >> and
> >>> > >> > > > >> > created *host-only
> >>> > >> > > > >> > network 192.168.56.1/24 <http://192.168.56.1/24>*
> >>> > >> > > > >> > Devcloud is pinged now.
> >>> > >> > > > >> >
> >>> > >> > > > >> > I built and run cloudstack on my local machine with
> >>> commands
> >>> > >> > > > >> > *mvn -Pdeveloper,systemvm clean install*
> >>> > >> > > > >> > *mvn -Pdeveloper -pl developer,tools/devcloud
> -Ddeploydb*
> >>> > >> > > > >> > *mvn -pl :cloud-client-ui jetty:run*
> >>> > >> > > > >> >
> >>> > >> > > > >> >
> >>> > >> > > > >> > Then I installed marvin and tried to deploy datacenter
> >>> with
> >>> > >> > command
> >>> > >> > > > >> > *mvn -P developer -pl tools/devcloud -Ddeploysvr*
> >>> > >> > > > >> > and got exception
> >>> > >> > > > >> >
> >>> > >> > > > >> > *==== Deploy DC Started ====*
> >>> > >> > > > >> > *Exception Occurred :['Traceback (most recent call
> >>> > last):\n', '
> >>> > >> > > File
> >>> > >> > > > >> > "../marvin/marvin/deployDataCenter.py", line 136, in
> >>> > addHosts\n
> >>> > >> > > > ret =
> >>> > >> > > > >> > self.__apiClient.addHost(hostcmd)\n', ' File
> >>> > >> > > > >> >
> >>> > >> > > > >> >
> >>> > >> > > > >>
> >>> > >> > > >
> >>> > >> > >
> >>> > >> >
> >>> > >>
> >>> >
> >>>
> "/usr/local/lib/python2.7/dist-packages/Marvin-4.6.0_SNAPSHOT-py2.7.egg/marvin/cloudstackAPI/cloudstackAPIClient.py",
> >>> > >> > > > >> > line 1572, in addHost\n response =
> >>> > >> > > > >> > self.connection.marvinRequest(command,
> >>> > response_type=response,
> >>> > >> > > > >> > method=method)\n', ' File
> >>> > >> > > > >> >
> >>> > >> > > > >> >
> >>> > >> > > > >>
> >>> > >> > > >
> >>> > >> > >
> >>> > >> >
> >>> > >>
> >>> >
> >>>
> "/usr/local/lib/python2.7/dist-packages/Marvin-4.6.0_SNAPSHOT-py2.7.egg/marvin/cloudstackConnection.py",
> >>> > >> > > > >> > line 379, in marvinRequest\n raise e\n',
> >>> > >> > 'CloudstackAPIException:
> >>> > >> > > > >> > Execute cmd: addhost failed, due to: errorCode: 530,
> >>> > >> > > errorText:Cannot
> >>> > >> > > > >> > transit agent status with event AgentDisconnected for
> >>> host 1,
> >>> > >> > > > mangement
> >>> > >> > > > >> > server id is 18413734744879,Unable to transition to a
> new
> >>> > state
> >>> > >> > from
> >>> > >> > > > >> > Creating via AgentDisconnected\n']*
> >>> > >> > > > >> >
> >>> > >> > > > >> > *===deploy dc failed, so cleaning the created
> entries===*
> >>> > >> > > > >> >
> >>> > >> > > > >> > *====DeployDC: CleanUp Started====*
> >>> > >> > > > >> >
> >>> > >> > > > >> > *====Clean Up Entries=== {'Network':
> >>> > >> > > > >> > [u'5720be8d-f8eb-4dee-af4f-9551409c8722'], 'Zone':
> >>> > >> > > > >> > [u'd05cb102-2766-4d59-a94b-0998842b3717'],
> >>> 'PhysicalNetwork':
> >>> > >> > > > >> > [u'd641f7e3-0996-4064-a6ce-54620c0524e2'], 'Cluster':
> >>> > >> > > > >> > [u'eb213a95-4b5b-4a7d-9b4d-be1754a3b932'], 'Pod':
> >>> > >> > > > >> > [u'659bc864-4d10-48c9-83ef-30ee40f1ce8d'], 'order':
> >>> > ['Cluster',
> >>> > >> > > 'Pod',
> >>> > >> > > > >> > 'Network', 'PhysicalNetwork', 'Zone']}*
> >>> > >> > > > >> >
> >>> > >> > > > >> > *===Removing DataCenter Failed===*
> >>> > >> > > > >> >
> >>> > >> > > > >> > As a result in cloudstack were created zone, cluster,
> pod,
> >>> > and
> >>> > >> a
> >>> > >> > > host
> >>> > >> > > > >> with
> >>> > >> > > > >> > state "Alert".
> >>> > >> > > > >> >
> >>> > >> > > > >> > Also I run
> >>> > >> > > > >> > *python tools/marvin/marvin/deployDataCenter.py -i
> >>> > >> > > > >> > tools/devcloud/devcloud.cfg*
> >>> > >> > > > >> > that gave same result.
> >>> > >> > > > >> >
> >>> > >> > > > >> > What am I doing wrong?
> >>> > >> > > > >> >
> >>> > >> > > > >>
> >>> > >> > > > >>
> >>> > >> > > > >>
> >>> > >> > > > >> --
> >>> > >> > > > >> Daan
> >>> > >> > > > >>
> >>> > >> > > > >
> >>> > >> > > > >
> >>> > >> > > >
> >>> > >> > >
> >>> > >> > >
> >>> > >> > >
> >>> > >> > > --
> >>> > >> > > Daan
> >>> > >> > >
> >>> > >> >
> >>> > >>
> >>> > >>
> >>> > >>
> >>> > >> --
> >>> > >> Daan
> >>> > >>
> >>> > >
> >>> > >
> >>> >
> >>>
> >>
> >>
> Find out more about ShapeBlue and our range of CloudStack related services
>
> IaaS Cloud Design & Build<
> http://shapeblue.com/iaas-cloud-design-and-build//>
> CSForge – rapid IaaS deployment framework<http://shapeblue.com/csforge/>
> CloudStack Consulting<http://shapeblue.com/cloudstack-consultancy/>
> CloudStack Software Engineering<
> http://shapeblue.com/cloudstack-software-engineering/>
> CloudStack Infrastructure Support<
> http://shapeblue.com/cloudstack-infrastructure-support/>
> CloudStack Bootcamp Training Courses<
> http://shapeblue.com/cloudstack-training/>
>
> This email and any attachments to it may be confidential and are intended
> solely for the use of the individual to whom it is addressed. Any views or
> opinions expressed are solely those of the author and do not necessarily
> represent those of Shape Blue Ltd or related companies. If you are not the
> intended recipient of this email, you must neither take any action based
> upon its contents, nor copy or show it to anyone. Please contact the sender
> if you believe you have received this email in error. Shape Blue Ltd is a
> company incorporated in England & Wales. ShapeBlue Services India LLP is a
> company incorporated in India and is operated under license from Shape Blue
> Ltd. Shape Blue Brasil Consultoria Ltda is a company incorporated in Brasil
> and is operated under license from Shape Blue Ltd. ShapeBlue SA Pty Ltd is
> a company registered by The Republic of South Africa and is traded under
> license from Shape Blue Ltd. ShapeBlue is a registered trademark.
>
[20151129T13:22:15.019Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:411759a52df5|xapi] Successful local authentication user root from HTTP request from Internet with User-Agent: Apache XML RPC 3.1.3 (Sun HTTP Transport)
[20151129T13:22:15.020Z| info|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:411759a52df5|xapi] Session.create trackid=17cea8d14b5e957a858ad82864b459fc pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.021Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:411759a52df5|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.022Z|debug|devcloud|110 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:ad1534befdba created by task D:411759a52df5
[20151129T13:22:15.323Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|backtrace] Raised at db_cache_impl.ml:192.16-55 -> db_actions.ml:3476.34-75 -> rbac.ml:229.16-23 -> rbac.ml:238.10-15 -> server_helpers.ml:76.11-23
[20151129T13:22:15.323Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|dispatcher] Server_helpers.exec exception_handler: Got exception UUID_INVALID: [ pool_patch; 996dd2e7-ad95-49cc-a0be-2c9adc4dfb0b ]
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|dispatcher] Raised at string.ml:150.25-34 -> stringext.ml:108.13-29
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|backtrace] Raised at string.ml:150.25-34 -> stringext.ml:108.13-29
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|xapi] Raised at server_helpers.ml:94.14-15 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:2237231b8859|xapi] Raised at pervasiveext.ml:26.22-25 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|dispatch:pool_patch.get_by_uuid D:c269f85697d9|xapi] Raised at pervasiveext.ml:26.22-25 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.324Z|debug|devcloud|109 INET 127.0.0.1:80|dispatch:pool_patch.get_by_uuid D:c269f85697d9|backtrace] Raised at pervasiveext.ml:26.22-25 -> server_helpers.ml:153.10-106 -> server.ml:4456.19-186 -> server_helpers.ml:119.4-7
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|backtrace] Raised at db_cache_impl.ml:192.16-55 -> db_actions.ml:3476.34-75 -> rbac.ml:229.16-23 -> rbac.ml:238.10-15 -> server_helpers.ml:76.11-23
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|dispatcher] Server_helpers.exec exception_handler: Got exception UUID_INVALID: [ pool_patch; 0850b186-4d47-11e3-a720-001b2151a503 ]
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|dispatcher] Raised at string.ml:150.25-34 -> stringext.ml:108.13-29
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|backtrace] Raised at string.ml:150.25-34 -> stringext.ml:108.13-29
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|xapi] Raised at server_helpers.ml:94.14-15 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|pool_patch.get_by_uuid D:bf70773351f2|xapi] Raised at pervasiveext.ml:26.22-25 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.339Z|debug|devcloud|109 INET 127.0.0.1:80|dispatch:pool_patch.get_by_uuid D:71931e59e6fa|xapi] Raised at pervasiveext.ml:26.22-25 -> pervasiveext.ml:22.2-9
[20151129T13:22:15.340Z|debug|devcloud|109 INET 127.0.0.1:80|dispatch:pool_patch.get_by_uuid D:71931e59e6fa|backtrace] Raised at pervasiveext.ml:26.22-25 -> server_helpers.ml:153.10-106 -> server.ml:4456.19-186 -> server_helpers.ml:119.4-7
[20151129T13:22:15.395Z| info|devcloud|109 INET 127.0.0.1:80|session.logout D:026dc76ea876|xapi] Session.destroy trackid=17cea8d14b5e957a858ad82864b459fc
[20151129T13:22:15.460Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:30da5acb24e7|xapi] Successful local authentication user root from HTTP request from Internet with User-Agent: Apache XML RPC 3.1.3 (Sun HTTP Transport)
[20151129T13:22:15.461Z| info|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:30da5acb24e7|xapi] Session.create trackid=f8fb010a8959daaa8983fdba61a8e1c9 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.461Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:30da5acb24e7|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.462Z|debug|devcloud|111 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:9971002924f1 created by task D:30da5acb24e7
[20151129T13:22:15.507Z| info|devcloud|109 INET 127.0.0.1:80|session.logout D:4416d8784464|xapi] Session.destroy trackid=f8fb010a8959daaa8983fdba61a8e1c9
[20151129T13:22:15.549Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:96a9f3aa5cd3|xapi] Successful local authentication user root from HTTP request from Internet with User-Agent: Apache XML RPC 3.1.3 (Sun HTTP Transport)
[20151129T13:22:15.550Z| info|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:96a9f3aa5cd3|xapi] Session.create trackid=072e536ff31060222a32b111dcb5242a pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.551Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:96a9f3aa5cd3|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.551Z|debug|devcloud|112 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:c5664496f6ef created by task D:96a9f3aa5cd3
[20151129T13:22:15.584Z| info|devcloud|109 INET 127.0.0.1:80|session.logout D:6c5e30d89887|xapi] Session.destroy trackid=072e536ff31060222a32b111dcb5242a
[20151129T13:22:15.619Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:4f14854ba000|xapi] Successful local authentication user root from HTTP request from Internet with User-Agent: Apache XML RPC 3.1.3 (Sun HTTP Transport)
[20151129T13:22:15.620Z| info|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:4f14854ba000|xapi] Session.create trackid=762c2d87ce4a50e730c6adf86ef75369 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.620Z|debug|devcloud|109 INET 127.0.0.1:80|session.login_with_password D:4f14854ba000|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.621Z|debug|devcloud|113 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:1440f6cc334d created by task D:4f14854ba000
[20151129T13:22:15.738Z| info|devcloud|5 dbflush [/var/lib/xcp/state.db]||redo_log] Flushing database to all active redo-logs
[20151129T13:22:15.864Z|debug|devcloud|109 INET 127.0.0.1:80|SR.scan R:f5727b6a1460|audit] SR.scan: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:22:15.865Z|debug|devcloud|109 INET 127.0.0.1:80|SR.scan R:f5727b6a1460|xapi] Marking SR for SR.scan (task=OpaqueRef:f5727b6a-1460-8071-d3ff-8d609d817116)
[20151129T13:22:15.866Z| info|devcloud|109 INET 127.0.0.1:80|SR.scan R:f5727b6a1460|storage_impl] SR.scan task:OpaqueRef:f5727b6a-1460-8071-d3ff-8d609d817116 sr:9f3f9262-3f77-09cc-2df7-0d8475676260
[20151129T13:22:15.867Z|debug|devcloud|109 INET 127.0.0.1:80|SR.scan R:f5727b6a1460|dummytaskhelper] task SR.scan D:19d320ae9e73 created by task R:f5727b6a1460
[20151129T13:22:15.867Z|debug|devcloud|109 INET 127.0.0.1:80|SR.scan D:19d320ae9e73|sm] SM file sr_scan sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:22:15.868Z| info|devcloud|109 INET 127.0.0.1:80|sm_exec D:6773892e6f25|xapi] Session.create trackid=ffccf9d8ead99c0087099942d513b3d9 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.869Z|debug|devcloud|109 INET 127.0.0.1:80|sm_exec D:6773892e6f25|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.869Z|debug|devcloud|114 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:a9706465c951 created by task D:6773892e6f25
[20151129T13:22:15.971Z|debug|devcloud|115 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:dfe63efe60c6 created by task D:19d320ae9e73
[20151129T13:22:15.976Z|debug|devcloud|115 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:15.992Z|debug|devcloud|116 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:c7eb059f8559 created by task D:19d320ae9e73
[20151129T13:22:15.995Z| info|devcloud|117 UNIX /var/lib/xcp/xapi|session.login_with_password D:f5ebf0d04961|xapi] Session.create trackid=f6ebe2abad814c2c29851cf31bd56215 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:15.996Z|debug|devcloud|117 UNIX /var/lib/xcp/xapi|session.login_with_password D:f5ebf0d04961|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:15.997Z|debug|devcloud|116 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:15.997Z|debug|devcloud|119 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:92edefe882d7 created by task D:19d320ae9e73
[20151129T13:22:16.000Z|debug|devcloud|118 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:69ea60613048 created by task D:f5ebf0d04961
[20151129T13:22:16.002Z|debug|devcloud|117 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.005Z|debug|devcloud|119 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.006Z|debug|devcloud|120 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.007Z|debug|devcloud|121 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:8bfa0717d781 created by task D:19d320ae9e73
[20151129T13:22:16.012Z|debug|devcloud|121 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.012Z|debug|devcloud|122 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.013Z|debug|devcloud|123 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:802bc351d5cb created by task D:19d320ae9e73
[20151129T13:22:16.018Z|debug|devcloud|123 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.018Z|debug|devcloud|124 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.019Z|debug|devcloud|125 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:a089321273a4 created by task D:19d320ae9e73
[20151129T13:22:16.023Z|debug|devcloud|125 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.023Z|debug|devcloud|126 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.024Z|debug|devcloud|127 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_all_records_where D:2fcf902a280f created by task D:19d320ae9e73
[20151129T13:22:16.031Z|debug|devcloud|127 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.036Z|debug|devcloud|128 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.040Z|debug|devcloud|129 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.043Z| info|devcloud|109 INET 127.0.0.1:80|sm_exec D:6773892e6f25|xapi] Session.destroy trackid=ffccf9d8ead99c0087099942d513b3d9
[20151129T13:22:16.044Z|debug|devcloud|109 INET 127.0.0.1:80|SR.scan R:f5727b6a1460|xapi] Unmarking SR after SR.scan (task=OpaqueRef:f5727b6a-1460-8071-d3ff-8d609d817116)
[20151129T13:22:16.053Z|debug|devcloud|130 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.064Z|debug|devcloud|131 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.074Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_label R:bd4301bd686d|audit] SR.set_name_label: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)' name-label = '9f3f9262-3f77-09cc-2df7-0d8475676260'
[20151129T13:22:16.075Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_label R:bd4301bd686d|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.078Z|debug|devcloud|132 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.078Z|debug|devcloud|133 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:892fbe0ca19e created by task R:bd4301bd686d
[20151129T13:22:16.079Z| info|devcloud|133 UNIX /var/lib/xcp/xapi|session.slave_login D:695f6a341f39|xapi] Session.create trackid=8cf526fd14dc4b07c6720d67799d33cd pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:16.080Z|debug|devcloud|133 UNIX /var/lib/xcp/xapi|session.slave_login D:695f6a341f39|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.080Z|debug|devcloud|134 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:e79424b57c5f created by task D:695f6a341f39
[20151129T13:22:16.082Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_label R:bd4301bd686d|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.083Z|debug|devcloud|135 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.update D:09e220c0d041 created by task R:bd4301bd686d
[20151129T13:22:16.085Z| info|devcloud|135 UNIX /var/lib/xcp/xapi|dispatch:SR.update D:09e220c0d041|taskhelper] task SR.update R:9e3a2ae112e0 (uuid:603ccb09-9ec5-1676-9586-f0c209002577) created (trackid=8cf526fd14dc4b07c6720d67799d33cd) by task R:bd4301bd686d
[20151129T13:22:16.085Z|debug|devcloud|135 UNIX /var/lib/xcp/xapi|SR.update R:9e3a2ae112e0|audit] SR.update: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:22:16.085Z|debug|devcloud|135 UNIX /var/lib/xcp/xapi|SR.update R:9e3a2ae112e0|sm] SM file sr_update sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:22:16.087Z| info|devcloud|135 UNIX /var/lib/xcp/xapi|sm_exec D:1250b6981c93|xapi] Session.create trackid=7dac4f65186b307ca45103e5da1f0de7 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:16.088Z|debug|devcloud|135 UNIX /var/lib/xcp/xapi|sm_exec D:1250b6981c93|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.088Z|debug|devcloud|136 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:4cde150c8c3f created by task D:1250b6981c93
[20151129T13:22:16.186Z|debug|devcloud|137 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:d1639af21e30 created by task R:9e3a2ae112e0
[20151129T13:22:16.189Z|debug|devcloud|137 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.189Z|debug|devcloud|138 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_virtual_allocation D:ef88daf398d0 created by task R:9e3a2ae112e0
[20151129T13:22:16.192Z|debug|devcloud|138 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.192Z|debug|devcloud|139 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:b1c6af171720 created by task R:9e3a2ae112e0
[20151129T13:22:16.194Z|debug|devcloud|139 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.195Z|debug|devcloud|140 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:19819cc1d0f3 created by task R:9e3a2ae112e0
[20151129T13:22:16.198Z|debug|devcloud|140 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.199Z|debug|devcloud|141 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:0cb60c50e3ad created by task R:9e3a2ae112e0
[20151129T13:22:16.201Z|debug|devcloud|141 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.202Z|debug|devcloud|142 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:4686f21b0aa0 created by task R:9e3a2ae112e0
[20151129T13:22:16.210Z| info|devcloud|135 UNIX /var/lib/xcp/xapi|sm_exec D:1250b6981c93|xapi] Session.destroy trackid=7dac4f65186b307ca45103e5da1f0de7
[20151129T13:22:16.214Z|debug|devcloud|142 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.214Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_label R:bd4301bd686d|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.214Z|debug|devcloud|143 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout D:37420cb63117 created by task R:bd4301bd686d
[20151129T13:22:16.216Z| info|devcloud|143 UNIX /var/lib/xcp/xapi|session.logout D:77956b5e4833|xapi] Session.destroy trackid=8cf526fd14dc4b07c6720d67799d33cd
[20151129T13:22:16.225Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_description R:ca4807c403cf|audit] SR.set_name_description: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)' name-description = 'Cloud Stack Local EXT Storage Pool for 9d4c9db8-32f7-25c3-0435-eab4bf3adcea'
[20151129T13:22:16.225Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_description R:ca4807c403cf|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.226Z|debug|devcloud|144 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:0de250b975df created by task R:ca4807c403cf
[20151129T13:22:16.227Z| info|devcloud|144 UNIX /var/lib/xcp/xapi|session.slave_login D:49297235490a|xapi] Session.create trackid=073be13d0e61bac85cf6a876036f7724 pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:16.228Z|debug|devcloud|144 UNIX /var/lib/xcp/xapi|session.slave_login D:49297235490a|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.229Z|debug|devcloud|145 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:7c9bf076429e created by task D:49297235490a
[20151129T13:22:16.231Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_description R:ca4807c403cf|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.231Z|debug|devcloud|146 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.update D:b25b58fe82ef created by task R:ca4807c403cf
[20151129T13:22:16.234Z| info|devcloud|146 UNIX /var/lib/xcp/xapi|dispatch:SR.update D:b25b58fe82ef|taskhelper] task SR.update R:3ba4410400bc (uuid:20a9a0d3-7b75-1cce-b964-98c0f8af6c4d) created (trackid=073be13d0e61bac85cf6a876036f7724) by task R:ca4807c403cf
[20151129T13:22:16.234Z|debug|devcloud|146 UNIX /var/lib/xcp/xapi|SR.update R:3ba4410400bc|audit] SR.update: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:22:16.234Z|debug|devcloud|146 UNIX /var/lib/xcp/xapi|SR.update R:3ba4410400bc|sm] SM file sr_update sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:22:16.235Z| info|devcloud|146 UNIX /var/lib/xcp/xapi|sm_exec D:f331c5ed12b1|xapi] Session.create trackid=9407420bb2cb69d63933b089632a941f pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:22:16.236Z|debug|devcloud|146 UNIX /var/lib/xcp/xapi|sm_exec D:f331c5ed12b1|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.236Z|debug|devcloud|147 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:c32c7759b1bc created by task D:f331c5ed12b1
[20151129T13:22:16.335Z|debug|devcloud|148 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:ee04fb27de67 created by task R:3ba4410400bc
[20151129T13:22:16.338Z|debug|devcloud|148 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.339Z|debug|devcloud|149 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_virtual_allocation D:c3d7e0f96905 created by task R:3ba4410400bc
[20151129T13:22:16.341Z|debug|devcloud|149 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.341Z|debug|devcloud|150 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:9dd47dd1643d created by task R:3ba4410400bc
[20151129T13:22:16.343Z|debug|devcloud|150 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.344Z|debug|devcloud|151 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:372a3a126b6c created by task R:3ba4410400bc
[20151129T13:22:16.347Z|debug|devcloud|151 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.347Z|debug|devcloud|152 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:094185757ef1 created by task R:3ba4410400bc
[20151129T13:22:16.350Z|debug|devcloud|152 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.350Z|debug|devcloud|153 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:e9bf4fac344f created by task R:3ba4410400bc
[20151129T13:22:16.356Z|debug|devcloud|153 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:22:16.359Z| info|devcloud|146 UNIX /var/lib/xcp/xapi|sm_exec D:f331c5ed12b1|xapi] Session.destroy trackid=9407420bb2cb69d63933b089632a941f
[20151129T13:22:16.362Z|debug|devcloud|109 INET 127.0.0.1:80|SR.set_name_description R:ca4807c403cf|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:22:16.363Z|debug|devcloud|154 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout D:8e1fd40f0541 created by task R:ca4807c403cf
[20151129T13:22:16.364Z| info|devcloud|154 UNIX /var/lib/xcp/xapi|session.logout D:1f855c63f27c|xapi] Session.destroy trackid=073be13d0e61bac85cf6a876036f7724
[20151129T13:22:17.045Z|debug|devcloud|109 INET 127.0.0.1:80|host.call_plugin R:23479ad12b10|audit] Host.call_plugin host = '9d4c9db8-32f7-25c3-0435-eab4bf3adcea (devcloud)'; plugin = 'vmops'; fn = 'setIptables'; args = [ ]
[20151129T13:22:17.701Z|debug|devcloud|109 INET 127.0.0.1:80|host.call_plugin R:373981234cb6|audit] Host.call_plugin host = '9d4c9db8-32f7-25c3-0435-eab4bf3adcea (devcloud)'; plugin = 'vmops'; fn = 'can_bridge_firewall'; args = [ host_uuid: 9d4c9db8-32f7-25c3-0435-eab4bf3adcea; instance: VM ]
[20151129T13:22:25.063Z|debug|devcloud|109 INET 127.0.0.1:80||http_critical] Premature termination of connection!
[20151129T13:23:34.333Z|debug|devcloud|19|session_revalidation_func D:999fa00a4f88|xapi] revalidating all external sessions in the local host
[20151129T13:23:34.334Z|debug|devcloud|19|Starting periodic scheduler D:2119c699d1d4|backgroundscheduler] Adding function Revalidating externally-authenticated sessions to queue, start=300.000000, type=Periodic(300.000000)
[20151129T13:23:34.334Z|debug|devcloud|19|Starting periodic scheduler D:2119c699d1d4|backgroundscheduler] Sleeping until next event (120.170907 seconds)
[20151129T13:25:14.321Z|debug|devcloud|155 INET 127.0.0.1:80|session.login_with_password D:f5eb8012e88f|xapi] Successful local authentication user root from HTTP request from Internet with User-Agent: Apache XML RPC 3.1.3 (Sun HTTP Transport)
[20151129T13:25:14.322Z| info|devcloud|155 INET 127.0.0.1:80|session.login_with_password D:f5eb8012e88f|xapi] Session.create trackid=6d1a72e8209f13240ef9dce9c9c4682e pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:14.323Z|debug|devcloud|155 INET 127.0.0.1:80|session.login_with_password D:f5eb8012e88f|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.324Z|debug|devcloud|156 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:6d6ea671dbcc created by task D:f5eb8012e88f
[20151129T13:25:14.379Z| info|devcloud|155 INET 127.0.0.1:80|session.logout D:d5f774f13bab|xapi] Session.destroy trackid=6d1a72e8209f13240ef9dce9c9c4682e
[20151129T13:25:14.622Z|debug|devcloud|155 INET 127.0.0.1:80|SR.scan R:bd9d7094c571|audit] SR.scan: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:25:14.623Z|debug|devcloud|155 INET 127.0.0.1:80|SR.scan R:bd9d7094c571|xapi] Marking SR for SR.scan (task=OpaqueRef:bd9d7094-c571-ba4b-f309-bf4dd9f53d31)
[20151129T13:25:14.624Z| info|devcloud|155 INET 127.0.0.1:80|SR.scan R:bd9d7094c571|storage_impl] SR.scan task:OpaqueRef:bd9d7094-c571-ba4b-f309-bf4dd9f53d31 sr:9f3f9262-3f77-09cc-2df7-0d8475676260
[20151129T13:25:14.625Z|debug|devcloud|155 INET 127.0.0.1:80|SR.scan R:bd9d7094c571|dummytaskhelper] task SR.scan D:1d04e4535616 created by task R:bd9d7094c571
[20151129T13:25:14.625Z|debug|devcloud|155 INET 127.0.0.1:80|SR.scan D:1d04e4535616|sm] SM file sr_scan sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:25:14.626Z| info|devcloud|155 INET 127.0.0.1:80|sm_exec D:ce87376a3d7f|xapi] Session.create trackid=2edbd4e1f614d3bfe14df5704c976018 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:14.627Z|debug|devcloud|155 INET 127.0.0.1:80|sm_exec D:ce87376a3d7f|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.628Z|debug|devcloud|157 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:d29d92544b04 created by task D:ce87376a3d7f
[20151129T13:25:14.740Z|debug|devcloud|158 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:d3e22c16a650 created by task D:1d04e4535616
[20151129T13:25:14.745Z|debug|devcloud|158 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.763Z|debug|devcloud|159 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:cfed11b6d1d7 created by task D:1d04e4535616
[20151129T13:25:14.767Z|debug|devcloud|159 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.768Z| info|devcloud|160 UNIX /var/lib/xcp/xapi|session.login_with_password D:225154df8f3b|xapi] Session.create trackid=221b029911b9de20694dc8c189977e76 pool=false uname=root is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:14.769Z|debug|devcloud|160 UNIX /var/lib/xcp/xapi|session.login_with_password D:225154df8f3b|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.769Z|debug|devcloud|161 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:4cbd061da1ec created by task D:1d04e4535616
[20151129T13:25:14.772Z|debug|devcloud|162 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:f9e44198b68d created by task D:225154df8f3b
[20151129T13:25:14.774Z|debug|devcloud|161 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.776Z|debug|devcloud|160 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.777Z|debug|devcloud|163 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:e15cc0f4dd1d created by task D:1d04e4535616
[20151129T13:25:14.782Z|debug|devcloud|164 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.783Z|debug|devcloud|163 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.786Z|debug|devcloud|166 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:96d908eec183 created by task D:1d04e4535616
[20151129T13:25:14.788Z|debug|devcloud|165 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.789Z|debug|devcloud|166 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.792Z|debug|devcloud|168 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:0c8a4de621e7 created by task D:1d04e4535616
[20151129T13:25:14.794Z|debug|devcloud|167 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.795Z|debug|devcloud|168 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.798Z|debug|devcloud|170 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:VDI.get_all_records_where D:352b1e09e9fb created by task D:1d04e4535616
[20151129T13:25:14.800Z|debug|devcloud|169 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.807Z|debug|devcloud|170 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.810Z|debug|devcloud|171 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.814Z| info|devcloud|155 INET 127.0.0.1:80|sm_exec D:ce87376a3d7f|xapi] Session.destroy trackid=2edbd4e1f614d3bfe14df5704c976018
[20151129T13:25:14.815Z|debug|devcloud|155 INET 127.0.0.1:80|SR.scan R:bd9d7094c571|xapi] Unmarking SR after SR.scan (task=OpaqueRef:bd9d7094-c571-ba4b-f309-bf4dd9f53d31)
[20151129T13:25:14.823Z|debug|devcloud|172 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.834Z|debug|devcloud|173 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.843Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_label R:d983b8627c2e|audit] SR.set_name_label: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)' name-label = '9f3f9262-3f77-09cc-2df7-0d8475676260'
[20151129T13:25:14.843Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_label R:d983b8627c2e|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.846Z|debug|devcloud|174 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.849Z|debug|devcloud|175 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:dc643b168921 created by task R:d983b8627c2e
[20151129T13:25:14.850Z| info|devcloud|175 UNIX /var/lib/xcp/xapi|session.slave_login D:4690d8156ff1|xapi] Session.create trackid=67fbb4a7afb911cf43355b366efacffe pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:14.851Z|debug|devcloud|175 UNIX /var/lib/xcp/xapi|session.slave_login D:4690d8156ff1|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.854Z|debug|devcloud|176 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.854Z|debug|devcloud|177 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:d33c03327709 created by task D:4690d8156ff1
[20151129T13:25:14.856Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_label R:d983b8627c2e|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.857Z|debug|devcloud|178 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.update D:d269a936c7fd created by task R:d983b8627c2e
[20151129T13:25:14.860Z| info|devcloud|178 UNIX /var/lib/xcp/xapi|dispatch:SR.update D:d269a936c7fd|taskhelper] task SR.update R:3d0a9bf99abb (uuid:361dc3c9-ae41-fae9-9977-f89b7b51901a) created (trackid=67fbb4a7afb911cf43355b366efacffe) by task R:d983b8627c2e
[20151129T13:25:14.860Z|debug|devcloud|178 UNIX /var/lib/xcp/xapi|SR.update R:3d0a9bf99abb|audit] SR.update: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:25:14.860Z|debug|devcloud|178 UNIX /var/lib/xcp/xapi|SR.update R:3d0a9bf99abb|sm] SM file sr_update sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:25:14.862Z| info|devcloud|178 UNIX /var/lib/xcp/xapi|sm_exec D:e61aed97ad08|xapi] Session.create trackid=ec879b27afb2fa88882eebad490af5f2 pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:14.862Z|debug|devcloud|178 UNIX /var/lib/xcp/xapi|sm_exec D:e61aed97ad08|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:14.863Z|debug|devcloud|179 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:e96aa0ddc41e created by task D:e61aed97ad08
[20151129T13:25:14.970Z|debug|devcloud|180 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:34eee2129fd9 created by task R:3d0a9bf99abb
[20151129T13:25:14.972Z|debug|devcloud|180 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.973Z|debug|devcloud|181 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_virtual_allocation D:f2d15ac3fc42 created by task R:3d0a9bf99abb
[20151129T13:25:14.976Z|debug|devcloud|181 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.976Z|debug|devcloud|182 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:4e30745c4a3b created by task R:3d0a9bf99abb
[20151129T13:25:14.979Z|debug|devcloud|182 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.980Z|debug|devcloud|183 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:8fadbb5c607c created by task R:3d0a9bf99abb
[20151129T13:25:14.983Z|debug|devcloud|183 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.984Z|debug|devcloud|184 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:b6acfbea2f58 created by task R:3d0a9bf99abb
[20151129T13:25:14.987Z|debug|devcloud|184 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:14.988Z|debug|devcloud|185 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:77565ac3a7dc created by task R:3d0a9bf99abb
[20151129T13:25:14.997Z| info|devcloud|178 UNIX /var/lib/xcp/xapi|sm_exec D:e61aed97ad08|xapi] Session.destroy trackid=ec879b27afb2fa88882eebad490af5f2
[20151129T13:25:15.001Z|debug|devcloud|185 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.001Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_label R:d983b8627c2e|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.002Z|debug|devcloud|186 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout D:b7a5dee06a8d created by task R:d983b8627c2e
[20151129T13:25:15.003Z| info|devcloud|186 UNIX /var/lib/xcp/xapi|session.logout D:c3ae81ec7a87|xapi] Session.destroy trackid=67fbb4a7afb911cf43355b366efacffe
[20151129T13:25:15.014Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_description R:87f8b9dc2b32|audit] SR.set_name_description: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)' name-description = 'Cloud Stack Local EXT Storage Pool for 9d4c9db8-32f7-25c3-0435-eab4bf3adcea'
[20151129T13:25:15.015Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_description R:87f8b9dc2b32|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.016Z|debug|devcloud|187 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.slave_login D:56b312387114 created by task R:87f8b9dc2b32
[20151129T13:25:15.017Z| info|devcloud|187 UNIX /var/lib/xcp/xapi|session.slave_login D:c87508d25b90|xapi] Session.create trackid=18285c4be562fd57705ff10fec9f6e70 pool=true uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:15.017Z|debug|devcloud|187 UNIX /var/lib/xcp/xapi|session.slave_login D:c87508d25b90|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.018Z|debug|devcloud|188 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:0b748f34cc74 created by task D:c87508d25b90
[20151129T13:25:15.019Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_description R:87f8b9dc2b32|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.020Z|debug|devcloud|189 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.update D:9e84a1f35f70 created by task R:87f8b9dc2b32
[20151129T13:25:15.022Z| info|devcloud|189 UNIX /var/lib/xcp/xapi|dispatch:SR.update D:9e84a1f35f70|taskhelper] task SR.update R:630470cc7ee1 (uuid:91e07de6-fa91-8232-3143-bd9b43f03376) created (trackid=18285c4be562fd57705ff10fec9f6e70) by task R:87f8b9dc2b32
[20151129T13:25:15.022Z|debug|devcloud|189 UNIX /var/lib/xcp/xapi|SR.update R:630470cc7ee1|audit] SR.update: SR = '9f3f9262-3f77-09cc-2df7-0d8475676260 (9f3f9262-3f77-09cc-2df7-0d8475676260)'
[20151129T13:25:15.022Z|debug|devcloud|189 UNIX /var/lib/xcp/xapi|SR.update R:630470cc7ee1|sm] SM file sr_update sr=OpaqueRef:75f9feeb-8309-b7b7-8455-9a1021a3baa4
[20151129T13:25:15.024Z| info|devcloud|189 UNIX /var/lib/xcp/xapi|sm_exec D:af90e2f31ef0|xapi] Session.create trackid=5cdec7e6d34463314ebcbfcf00b8c86c pool=false uname= is_local_superuser=true auth_user_sid= parent=trackid=9834f5af41c964e225f24279aefe4e49
[20151129T13:25:15.024Z|debug|devcloud|189 UNIX /var/lib/xcp/xapi|sm_exec D:af90e2f31ef0|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.025Z|debug|devcloud|190 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.get_uuid D:4c86b5f0a5f7 created by task D:af90e2f31ef0
[20151129T13:25:15.130Z|debug|devcloud|191 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:host.get_other_config D:d41522a9b777 created by task R:630470cc7ee1
[20151129T13:25:15.133Z|debug|devcloud|191 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.134Z|debug|devcloud|192 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_virtual_allocation D:e7b10de104a7 created by task R:630470cc7ee1
[20151129T13:25:15.136Z|debug|devcloud|192 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.137Z|debug|devcloud|193 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.get_by_uuid D:0ddcf12f1b6b created by task R:630470cc7ee1
[20151129T13:25:15.139Z|debug|devcloud|193 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.139Z|debug|devcloud|194 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_virtual_allocation D:74f5755bcd9e created by task R:630470cc7ee1
[20151129T13:25:15.143Z|debug|devcloud|194 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.143Z|debug|devcloud|195 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_size D:36cb9b741195 created by task R:630470cc7ee1
[20151129T13:25:15.146Z|debug|devcloud|195 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.147Z|debug|devcloud|196 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:SR.set_physical_utilisation D:51968295a102 created by task R:630470cc7ee1
[20151129T13:25:15.155Z| info|devcloud|189 UNIX /var/lib/xcp/xapi|sm_exec D:af90e2f31ef0|xapi] Session.destroy trackid=5cdec7e6d34463314ebcbfcf00b8c86c
[20151129T13:25:15.160Z|debug|devcloud|196 UNIX /var/lib/xcp/xapi||http_critical] Premature termination of connection!
[20151129T13:25:15.160Z|debug|devcloud|155 INET 127.0.0.1:80|SR.set_name_description R:87f8b9dc2b32|xapi] Attempting to open /var/lib/xcp/xapi
[20151129T13:25:15.160Z|debug|devcloud|197 UNIX /var/lib/xcp/xapi||dummytaskhelper] task dispatch:session.logout D:549e7f6ec5ac created by task R:87f8b9dc2b32
[20151129T13:25:15.162Z| info|devcloud|197 UNIX /var/lib/xcp/xapi|session.logout D:ff143edda161|xapi] Session.destroy trackid=18285c4be562fd57705ff10fec9f6e70
[20151129T13:25:15.349Z|debug|devcloud|155 INET 127.0.0.1:80|host.call_plugin R:30459927b8f5|audit] Host.call_plugin host = '9d4c9db8-32f7-25c3-0435-eab4bf3adcea (devcloud)'; plugin = 'vmops'; fn = 'setIptables'; args = [ ]
[20151129T13:25:15.539Z|debug|devcloud|155 INET 127.0.0.1:80|host.call_plugin R:bfdb27c5c85d|audit] Host.call_plugin host = '9d4c9db8-32f7-25c3-0435-eab4bf3adcea (devcloud)'; plugin = 'vmops'; fn = 'can_bridge_firewall'; args = [ host_uuid: 9d4c9db8-32f7-25c3-0435-eab4bf3adcea; instance: VM ]
[20151129T13:25:24.331Z|debug|devcloud|155 INET 127.0.0.1:80||http_critical] Premature termination of connection!
[20151129T13:25:34.578Z|debug|devcloud|19|Starting periodic scheduler D:2119c699d1d4|backgroundscheduler] Adding function Logrotate to queue, start=300.000000, type=Periodic(300.000000)
[20151129T13:25:34.578Z|debug|devcloud|19|Starting periodic scheduler D:2119c699d1d4|backgroundscheduler] Sleeping until next event (179.756831 seconds)
