From ebbd04ad032ac7ec025e19b4bb901caec810e0ae Mon Sep 17 00:00:00 2001 From: Ahmad Bilal Khalid Date: Thu, 11 Jul 2019 13:31:46 +0500 Subject: [PATCH] Totally revamped ucloud-vm --- .gitignore | 3 + etcd3_wrapper | 2 +- log.txt | 3167 ++++++++++++++++++++++++++++++++++++++++++ main.py | 288 ++-- qmp/__init__.py | 535 +++++++ qmp.py => qmp/qmp.py | 0 6 files changed, 3910 insertions(+), 85 deletions(-) create mode 100644 log.txt create mode 100644 qmp/__init__.py rename qmp.py => qmp/qmp.py (100%) diff --git a/.gitignore b/.gitignore index 3299c62..6644b00 100644 --- a/.gitignore +++ b/.gitignore @@ -3,3 +3,6 @@ __pycache__/ venv/ .env + +log.txt +vm_socklog/ diff --git a/etcd3_wrapper b/etcd3_wrapper index cb2a416..615a070 160000 --- a/etcd3_wrapper +++ b/etcd3_wrapper @@ -1 +1 @@ -Subproject commit cb2a416a17d6789e613ba3b9957917770f4211e1 +Subproject commit 615a0709186e74ee5c9ae78f385fd0c4c4b3834d diff --git a/log.txt b/log.txt new file mode 100644 index 0000000..355e462 --- /dev/null +++ b/log.txt @@ -0,0 +1,3167 @@ +05-Jul-19 14:46:53: INFO - Timeout +05-Jul-19 14:47:03: INFO - Timeout +05-Jul-19 14:47:13: INFO - Timeout +05-Jul-19 14:47:18: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:47:18: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:47:18: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp5tcfem_7/qemu-12676-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:47:18: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:47:18: DEBUG - <<< None +05-Jul-19 14:47:18: DEBUG - Error launching VM +05-Jul-19 14:47:18: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp5tcfem_7/qemu-12676-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:47:18: DEBUG - Output: "WARNING: Image format was not specified for '/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the 'raw' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Could not reopen file: Permission denied\n" +05-Jul-19 14:47:18: INFO - Cannot start already running vm - 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:47:18: INFO - Running VMs [] +05-Jul-19 14:47:18: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:47:18: INFO - Unknown Request +05-Jul-19 14:47:18: INFO - Running VMs [] +05-Jul-19 14:47:23: INFO - Timeout +05-Jul-19 14:47:23: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:47:23: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +05-Jul-19 14:47:23: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +05-Jul-19 14:47:33: INFO - Timeout +05-Jul-19 14:47:43: INFO - Timeout +05-Jul-19 14:47:53: INFO - Timeout +05-Jul-19 14:48:03: INFO - Timeout +05-Jul-19 14:48:13: INFO - Timeout +05-Jul-19 14:48:23: INFO - Timeout +05-Jul-19 14:48:33: INFO - Timeout +05-Jul-19 14:48:43: INFO - Timeout +05-Jul-19 14:49:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:49:00: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:49:00: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpsagjz5y0/qemu-12758-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:49:00: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:49:00: DEBUG - <<< None +05-Jul-19 14:49:00: DEBUG - Error launching VM +05-Jul-19 14:49:00: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpsagjz5y0/qemu-12758-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:49:00: DEBUG - Output: "WARNING: Image format was not specified for '/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the 'raw' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Could not reopen file: Permission denied\n" +05-Jul-19 14:49:00: INFO - Cannot start already running vm - 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:49:00: INFO - Running VMs [] +05-Jul-19 14:49:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:49:00: INFO - Unknown Request +05-Jul-19 14:49:00: INFO - Running VMs [] +05-Jul-19 14:49:04: INFO - Timeout +05-Jul-19 14:49:04: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:49:04: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +05-Jul-19 14:49:04: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +05-Jul-19 14:49:14: INFO - Timeout +05-Jul-19 14:49:24: INFO - Timeout +05-Jul-19 14:49:34: INFO - Timeout +05-Jul-19 14:49:44: INFO - Timeout +05-Jul-19 14:49:54: INFO - Timeout +05-Jul-19 14:50:04: INFO - Timeout +05-Jul-19 14:50:14: INFO - Timeout +05-Jul-19 14:50:24: INFO - Timeout +05-Jul-19 14:50:34: INFO - Timeout +05-Jul-19 14:50:44: INFO - Timeout +05-Jul-19 14:50:54: INFO - Timeout +05-Jul-19 14:51:04: INFO - Timeout +05-Jul-19 14:51:14: INFO - Timeout +05-Jul-19 14:51:24: INFO - Timeout +05-Jul-19 14:51:34: INFO - Timeout +05-Jul-19 14:51:44: INFO - Timeout +05-Jul-19 14:51:54: INFO - Timeout +05-Jul-19 14:52:04: INFO - Timeout +05-Jul-19 14:52:14: INFO - Timeout +05-Jul-19 14:52:24: INFO - Timeout +05-Jul-19 14:52:39: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:52:39: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:52:39: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp_g_bjjbp/qemu-12881-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:52:39: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:52:39: DEBUG - <<< None +05-Jul-19 14:52:39: DEBUG - Error launching VM +05-Jul-19 14:52:39: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp_g_bjjbp/qemu-12881-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:52:39: DEBUG - Output: "WARNING: Image format was not specified for '/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the 'raw' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Could not reopen file: Permission denied\n" +05-Jul-19 14:52:39: INFO - Machine Error Occurred on 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:52:39: INFO - Running VMs [] +05-Jul-19 14:52:43: INFO - Timeout +05-Jul-19 14:52:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:52:52: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:52:52: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpffn4a69h/qemu-12881-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:52:52: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:52:52: DEBUG - <<< None +05-Jul-19 14:52:52: DEBUG - Error launching VM +05-Jul-19 14:52:52: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpffn4a69h/qemu-12881-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:52:52: DEBUG - Output: "WARNING: Image format was not specified for '/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the 'raw' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Could not reopen file: Permission denied\n" +05-Jul-19 14:52:52: INFO - Machine Error Occurred on 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:52:52: INFO - Running VMs [] +05-Jul-19 14:52:53: INFO - Timeout +05-Jul-19 14:53:03: INFO - Timeout +05-Jul-19 14:53:13: INFO - Timeout +05-Jul-19 14:53:28: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:53:28: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:53:28: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp5xs35mvr/qemu-12965-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:53:28: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:53:28: DEBUG - <<< None +05-Jul-19 14:53:28: DEBUG - Error launching VM +05-Jul-19 14:53:28: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp5xs35mvr/qemu-12965-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:53:28: DEBUG - Output: "WARNING: Image format was not specified for '/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the 'raw' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Could not reopen file: Permission denied\n" +05-Jul-19 14:53:28: INFO - Machine Error Occurred on 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:53:28: INFO - Running VMs [] +05-Jul-19 14:53:28: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'KILLED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:53:28: INFO - Unknown Request +05-Jul-19 14:53:28: INFO - Running VMs [] +05-Jul-19 14:53:31: INFO - Timeout +05-Jul-19 14:53:41: INFO - Timeout +05-Jul-19 14:53:51: INFO - Timeout +05-Jul-19 14:54:01: INFO - Timeout +05-Jul-19 14:54:11: INFO - Timeout +05-Jul-19 14:54:21: INFO - Timeout +05-Jul-19 14:54:24: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:54:24: INFO - Starting 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:54:24: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpzncmjk5p/qemu-12965-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 14:54:24: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:54:24: DEBUG - <<< {'return': {}} +05-Jul-19 14:54:24: INFO - Started Successfully 9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 14:54:24: INFO - Running VMs [VM(id='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +05-Jul-19 14:54:24: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:54:24: INFO - Unknown Request +05-Jul-19 14:54:24: INFO - Running VMs [VM(id='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +05-Jul-19 14:54:31: INFO - Timeout +05-Jul-19 14:54:41: INFO - Timeout +05-Jul-19 14:54:51: INFO - Timeout +05-Jul-19 14:55:01: INFO - Timeout +05-Jul-19 14:55:11: INFO - Timeout +05-Jul-19 14:55:21: INFO - Timeout +05-Jul-19 14:55:31: INFO - Timeout +05-Jul-19 14:55:41: INFO - Timeout +05-Jul-19 14:55:51: INFO - Timeout +05-Jul-19 14:56:01: INFO - Timeout +05-Jul-19 14:56:11: INFO - Timeout +05-Jul-19 14:56:21: INFO - Timeout +05-Jul-19 14:56:31: INFO - Timeout +05-Jul-19 14:56:37: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:56:37: INFO - Starting 726ce8bdd289436da4a647dac79fd027 +05-Jul-19 14:56:37: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpg24qar3t/qemu-12965-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :1' +05-Jul-19 14:56:37: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 14:56:37: DEBUG - <<< {'return': {}} +05-Jul-19 14:56:37: INFO - Started Successfully 726ce8bdd289436da4a647dac79fd027 +05-Jul-19 14:56:37: INFO - Running VMs [VM(id='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(id='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 14:56:37: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:56:37: INFO - Unknown Request +05-Jul-19 14:56:37: INFO - Running VMs [VM(id='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(id='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 14:56:41: INFO - Timeout +05-Jul-19 14:56:51: INFO - Timeout +05-Jul-19 14:57:01: INFO - Timeout +05-Jul-19 14:57:11: INFO - Timeout +05-Jul-19 14:57:21: INFO - Timeout +05-Jul-19 14:57:31: INFO - Timeout +05-Jul-19 14:57:41: INFO - Timeout +05-Jul-19 14:57:51: INFO - Timeout +05-Jul-19 14:58:01: INFO - Timeout +05-Jul-19 14:58:11: INFO - Timeout +05-Jul-19 14:58:21: INFO - Timeout +05-Jul-19 14:58:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 14:58:22: INFO - Cannot suspend VM 726ce8bdd289436da4a647dac79fd027 that is not running +05-Jul-19 14:58:22: INFO - Running VMs [VM(id='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(id='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 14:58:31: INFO - Timeout +05-Jul-19 14:58:41: INFO - Timeout +05-Jul-19 14:58:51: INFO - Timeout +05-Jul-19 14:59:01: INFO - Timeout +05-Jul-19 14:59:11: INFO - Timeout +05-Jul-19 14:59:21: INFO - Timeout +05-Jul-19 14:59:31: INFO - Timeout +05-Jul-19 14:59:41: INFO - Timeout +05-Jul-19 14:59:51: INFO - Timeout +05-Jul-19 15:00:01: INFO - Timeout +05-Jul-19 15:00:11: INFO - Timeout +05-Jul-19 15:00:21: INFO - Timeout +05-Jul-19 15:00:31: INFO - Timeout +05-Jul-19 15:00:41: INFO - Timeout +05-Jul-19 15:00:51: INFO - Timeout +05-Jul-19 15:01:01: INFO - Timeout +05-Jul-19 15:01:11: INFO - Timeout +05-Jul-19 15:01:21: INFO - Timeout +05-Jul-19 15:01:31: INFO - Timeout +05-Jul-19 15:01:41: INFO - Timeout +05-Jul-19 15:01:51: INFO - Timeout +05-Jul-19 15:02:01: INFO - Timeout +05-Jul-19 15:02:11: INFO - Timeout +05-Jul-19 15:02:21: INFO - Timeout +05-Jul-19 15:02:31: INFO - Timeout +05-Jul-19 15:02:41: INFO - Timeout +05-Jul-19 15:02:51: INFO - Timeout +05-Jul-19 15:03:12: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:03:12: INFO - Cannot suspend VM /v1/vm/726ce8bdd289436da4a647dac79fd027 that is not running +05-Jul-19 15:03:12: INFO - Running VMs [] +05-Jul-19 15:03:17: INFO - Timeout +05-Jul-19 15:03:17: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 15:03:17: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +05-Jul-19 15:03:17: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +05-Jul-19 15:03:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:03:23: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 15:03:23: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpuzdmjcdz/qemu-13289-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 15:03:23: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 15:03:23: DEBUG - <<< {'return': {}} +05-Jul-19 15:03:23: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 15:03:23: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 15:03:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:03:23: INFO - Unknown Request +05-Jul-19 15:03:23: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 15:03:27: INFO - Timeout +05-Jul-19 15:03:59: INFO - Timeout +05-Jul-19 15:03:59: DEBUG - None /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 15:03:59: INFO - /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running but is said to be running +05-Jul-19 15:03:59: INFO - Updating /v1/vm/726ce8bdd289436da4a647dac79fd027 status to KILLED +05-Jul-19 15:04:09: INFO - Timeout +05-Jul-19 15:04:09: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:04:09: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 15:04:09: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpqgmk_puq/qemu-13328-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 15:04:09: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 15:04:09: DEBUG - <<< {'return': {}} +05-Jul-19 15:04:09: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 15:04:09: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 15:04:09: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:04:09: INFO - Unknown Request +05-Jul-19 15:04:09: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +05-Jul-19 15:04:19: INFO - Timeout +05-Jul-19 15:04:29: INFO - Timeout +05-Jul-19 15:04:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:04:34: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 15:04:34: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpgnbz17u5/qemu-13328-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +05-Jul-19 15:04:34: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 15:04:34: DEBUG - <<< {'return': {}} +05-Jul-19 15:04:34: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 15:04:34: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +05-Jul-19 15:04:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:04:34: INFO - Unknown Request +05-Jul-19 15:04:34: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +05-Jul-19 15:04:39: INFO - Timeout +05-Jul-19 15:04:49: INFO - Timeout +05-Jul-19 15:04:59: INFO - Timeout +05-Jul-19 15:05:09: INFO - Timeout +05-Jul-19 15:05:19: INFO - Timeout +05-Jul-19 15:05:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 15:05:22: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 15:05:22: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp8280v62s/qemu-13328-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :2' +05-Jul-19 15:05:22: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 15:05:22: DEBUG - <<< {'return': {}} +05-Jul-19 15:05:22: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 15:05:22: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 15:05:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 15:05:22: INFO - Unknown Request +05-Jul-19 15:05:22: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 15:05:29: INFO - Timeout +05-Jul-19 15:05:39: INFO - Timeout +05-Jul-19 15:05:49: INFO - Timeout +05-Jul-19 15:05:59: INFO - Timeout +05-Jul-19 15:05:59: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:05:59: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +05-Jul-19 15:05:59: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +05-Jul-19 15:05:59: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 15:05:59: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +05-Jul-19 15:05:59: INFO - Unknown Request +05-Jul-19 15:05:59: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 15:06:09: INFO - Timeout +05-Jul-19 15:06:19: INFO - Timeout +05-Jul-19 15:06:29: INFO - Timeout +05-Jul-19 15:06:39: INFO - Timeout +05-Jul-19 15:06:49: INFO - Timeout +05-Jul-19 15:06:59: INFO - Timeout +05-Jul-19 15:07:09: INFO - Timeout +05-Jul-19 15:07:19: INFO - Timeout +05-Jul-19 15:07:29: INFO - Timeout +05-Jul-19 15:07:39: INFO - Timeout +05-Jul-19 15:07:49: INFO - Timeout +05-Jul-19 15:07:59: INFO - Timeout +05-Jul-19 15:08:09: INFO - Timeout +05-Jul-19 15:08:19: INFO - Timeout +05-Jul-19 15:08:29: INFO - Timeout +05-Jul-19 15:08:39: INFO - Timeout +05-Jul-19 15:08:49: INFO - Timeout +05-Jul-19 15:08:59: INFO - Timeout +05-Jul-19 15:09:09: INFO - Timeout +05-Jul-19 15:09:19: INFO - Timeout +05-Jul-19 15:09:29: INFO - Timeout +05-Jul-19 15:09:39: INFO - Timeout +05-Jul-19 15:09:49: INFO - Timeout +05-Jul-19 15:09:59: INFO - Timeout +05-Jul-19 15:10:09: INFO - Timeout +05-Jul-19 15:10:19: INFO - Timeout +05-Jul-19 15:10:29: INFO - Timeout +05-Jul-19 15:10:39: INFO - Timeout +05-Jul-19 15:10:49: INFO - Timeout +05-Jul-19 15:10:59: INFO - Timeout +05-Jul-19 15:11:09: INFO - Timeout +05-Jul-19 15:11:19: INFO - Timeout +05-Jul-19 15:11:29: INFO - Timeout +05-Jul-19 15:11:39: INFO - Timeout +05-Jul-19 15:11:49: INFO - Timeout +05-Jul-19 15:11:59: INFO - Timeout +05-Jul-19 15:12:09: INFO - Timeout +05-Jul-19 15:12:19: INFO - Timeout +05-Jul-19 15:12:29: INFO - Timeout +05-Jul-19 15:12:39: INFO - Timeout +05-Jul-19 15:12:49: INFO - Timeout +05-Jul-19 15:12:59: INFO - Timeout +05-Jul-19 15:13:09: INFO - Timeout +05-Jul-19 15:13:19: INFO - Timeout +05-Jul-19 15:13:29: INFO - Timeout +05-Jul-19 15:13:39: INFO - Timeout +05-Jul-19 15:13:49: INFO - Timeout +05-Jul-19 15:13:59: INFO - Timeout +05-Jul-19 15:14:09: INFO - Timeout +05-Jul-19 15:14:19: INFO - Timeout +05-Jul-19 15:14:29: INFO - Timeout +05-Jul-19 15:14:39: INFO - Timeout +05-Jul-19 15:14:49: INFO - Timeout +05-Jul-19 15:14:59: INFO - Timeout +05-Jul-19 15:15:09: INFO - Timeout +05-Jul-19 15:15:19: INFO - Timeout +05-Jul-19 15:15:29: INFO - Timeout +05-Jul-19 15:15:39: INFO - Timeout +05-Jul-19 15:15:49: INFO - Timeout +05-Jul-19 15:15:59: INFO - Timeout +05-Jul-19 15:16:09: INFO - Timeout +05-Jul-19 15:16:19: INFO - Timeout +05-Jul-19 15:16:29: INFO - Timeout +05-Jul-19 15:16:39: INFO - Timeout +05-Jul-19 15:16:49: INFO - Timeout +05-Jul-19 15:16:59: INFO - Timeout +05-Jul-19 15:17:09: INFO - Timeout +05-Jul-19 15:17:19: INFO - Timeout +05-Jul-19 15:17:29: INFO - Timeout +05-Jul-19 15:17:39: INFO - Timeout +05-Jul-19 15:17:49: INFO - Timeout +05-Jul-19 15:17:59: INFO - Timeout +05-Jul-19 15:18:09: INFO - Timeout +05-Jul-19 15:18:19: INFO - Timeout +05-Jul-19 15:18:29: INFO - Timeout +05-Jul-19 15:18:39: INFO - Timeout +05-Jul-19 15:18:49: INFO - Timeout +05-Jul-19 15:18:59: INFO - Timeout +05-Jul-19 15:19:09: INFO - Timeout +05-Jul-19 15:19:19: INFO - Timeout +05-Jul-19 15:19:29: INFO - Timeout +05-Jul-19 15:19:39: INFO - Timeout +05-Jul-19 15:19:49: INFO - Timeout +05-Jul-19 15:19:59: INFO - Timeout +05-Jul-19 15:20:09: INFO - Timeout +05-Jul-19 15:20:19: INFO - Timeout +05-Jul-19 15:20:29: INFO - Timeout +05-Jul-19 15:20:39: INFO - Timeout +05-Jul-19 15:20:49: INFO - Timeout +05-Jul-19 15:20:59: INFO - Timeout +05-Jul-19 15:21:09: INFO - Timeout +05-Jul-19 15:21:19: INFO - Timeout +05-Jul-19 15:21:29: INFO - Timeout +05-Jul-19 15:21:39: INFO - Timeout +05-Jul-19 15:21:49: INFO - Timeout +05-Jul-19 15:21:59: INFO - Timeout +05-Jul-19 15:22:09: INFO - Timeout +05-Jul-19 15:22:19: INFO - Timeout +05-Jul-19 15:22:29: INFO - Timeout +05-Jul-19 15:22:39: INFO - Timeout +05-Jul-19 15:22:49: INFO - Timeout +05-Jul-19 15:22:59: INFO - Timeout +05-Jul-19 15:23:09: INFO - Timeout +05-Jul-19 15:23:19: INFO - Timeout +05-Jul-19 15:23:29: INFO - Timeout +05-Jul-19 15:23:39: INFO - Timeout +05-Jul-19 15:23:49: INFO - Timeout +05-Jul-19 15:23:59: INFO - Timeout +05-Jul-19 15:24:09: INFO - Timeout +05-Jul-19 15:24:19: INFO - Timeout +05-Jul-19 15:24:29: INFO - Timeout +05-Jul-19 15:24:39: INFO - Timeout +05-Jul-19 15:24:49: INFO - Timeout +05-Jul-19 15:24:59: INFO - Timeout +05-Jul-19 15:25:09: INFO - Timeout +05-Jul-19 15:25:19: INFO - Timeout +05-Jul-19 15:25:29: INFO - Timeout +05-Jul-19 15:25:39: INFO - Timeout +05-Jul-19 15:25:49: INFO - Timeout +05-Jul-19 15:25:59: INFO - Timeout +05-Jul-19 15:26:09: INFO - Timeout +05-Jul-19 15:26:19: INFO - Timeout +05-Jul-19 15:26:29: INFO - Timeout +05-Jul-19 15:26:39: INFO - Timeout +05-Jul-19 15:26:49: INFO - Timeout +05-Jul-19 15:26:59: INFO - Timeout +05-Jul-19 15:27:09: INFO - Timeout +05-Jul-19 15:27:19: INFO - Timeout +05-Jul-19 15:27:29: INFO - Timeout +05-Jul-19 15:27:39: INFO - Timeout +05-Jul-19 15:27:49: INFO - Timeout +05-Jul-19 15:27:59: INFO - Timeout +05-Jul-19 15:28:09: INFO - Timeout +05-Jul-19 15:28:19: INFO - Timeout +05-Jul-19 15:28:29: INFO - Timeout +05-Jul-19 15:28:39: INFO - Timeout +05-Jul-19 15:28:49: INFO - Timeout +05-Jul-19 15:28:59: INFO - Timeout +05-Jul-19 15:29:09: INFO - Timeout +05-Jul-19 15:29:19: INFO - Timeout +05-Jul-19 15:29:29: INFO - Timeout +05-Jul-19 15:29:39: INFO - Timeout +05-Jul-19 15:29:49: INFO - Timeout +05-Jul-19 15:29:59: INFO - Timeout +05-Jul-19 15:30:09: INFO - Timeout +05-Jul-19 15:30:19: INFO - Timeout +05-Jul-19 15:30:29: INFO - Timeout +05-Jul-19 15:30:39: INFO - Timeout +05-Jul-19 15:30:49: INFO - Timeout +05-Jul-19 15:30:59: INFO - Timeout +05-Jul-19 15:31:09: INFO - Timeout +05-Jul-19 15:31:19: INFO - Timeout +05-Jul-19 15:31:29: INFO - Timeout +05-Jul-19 15:31:39: INFO - Timeout +05-Jul-19 15:31:49: INFO - Timeout +05-Jul-19 15:31:59: INFO - Timeout +05-Jul-19 15:32:09: INFO - Timeout +05-Jul-19 15:32:19: INFO - Timeout +05-Jul-19 15:32:29: INFO - Timeout +05-Jul-19 15:32:39: INFO - Timeout +05-Jul-19 15:32:49: INFO - Timeout +05-Jul-19 15:32:59: INFO - Timeout +05-Jul-19 15:33:09: INFO - Timeout +05-Jul-19 15:33:19: INFO - Timeout +05-Jul-19 15:33:29: INFO - Timeout +05-Jul-19 15:33:39: INFO - Timeout +05-Jul-19 15:33:49: INFO - Timeout +05-Jul-19 15:33:59: INFO - Timeout +05-Jul-19 15:34:09: INFO - Timeout +05-Jul-19 15:34:19: INFO - Timeout +05-Jul-19 15:34:29: INFO - Timeout +05-Jul-19 15:34:39: INFO - Timeout +05-Jul-19 15:34:49: INFO - Timeout +05-Jul-19 15:34:59: INFO - Timeout +05-Jul-19 15:35:09: INFO - Timeout +05-Jul-19 15:35:19: INFO - Timeout +05-Jul-19 15:35:29: INFO - Timeout +05-Jul-19 15:35:39: INFO - Timeout +05-Jul-19 15:35:49: INFO - Timeout +05-Jul-19 15:35:59: INFO - Timeout +05-Jul-19 15:36:09: INFO - Timeout +05-Jul-19 15:36:19: INFO - Timeout +05-Jul-19 15:36:29: INFO - Timeout +05-Jul-19 15:36:39: INFO - Timeout +05-Jul-19 15:36:49: INFO - Timeout +05-Jul-19 15:36:59: INFO - Timeout +05-Jul-19 15:37:09: INFO - Timeout +05-Jul-19 15:37:19: INFO - Timeout +05-Jul-19 15:37:29: INFO - Timeout +05-Jul-19 15:37:39: INFO - Timeout +05-Jul-19 15:37:49: INFO - Timeout +05-Jul-19 15:37:59: INFO - Timeout +05-Jul-19 15:38:09: INFO - Timeout +05-Jul-19 15:38:19: INFO - Timeout +05-Jul-19 15:38:29: INFO - Timeout +05-Jul-19 15:38:39: INFO - Timeout +05-Jul-19 15:38:49: INFO - Timeout +05-Jul-19 15:38:59: INFO - Timeout +05-Jul-19 15:39:09: INFO - Timeout +05-Jul-19 15:39:19: INFO - Timeout +05-Jul-19 15:39:29: INFO - Timeout +05-Jul-19 15:39:39: INFO - Timeout +05-Jul-19 15:39:49: INFO - Timeout +05-Jul-19 15:39:59: INFO - Timeout +05-Jul-19 15:40:09: INFO - Timeout +05-Jul-19 15:40:19: INFO - Timeout +05-Jul-19 15:40:29: INFO - Timeout +05-Jul-19 15:40:39: INFO - Timeout +05-Jul-19 15:40:49: INFO - Timeout +05-Jul-19 15:40:59: INFO - Timeout +05-Jul-19 15:41:09: INFO - Timeout +05-Jul-19 15:41:19: INFO - Timeout +05-Jul-19 15:41:29: INFO - Timeout +05-Jul-19 15:41:39: INFO - Timeout +05-Jul-19 15:41:49: INFO - Timeout +05-Jul-19 15:41:59: INFO - Timeout +05-Jul-19 15:42:09: INFO - Timeout +05-Jul-19 15:42:19: INFO - Timeout +05-Jul-19 15:42:29: INFO - Timeout +05-Jul-19 15:42:39: INFO - Timeout +05-Jul-19 15:42:49: INFO - Timeout +05-Jul-19 15:42:59: INFO - Timeout +05-Jul-19 15:43:09: INFO - Timeout +05-Jul-19 15:43:19: INFO - Timeout +05-Jul-19 15:43:29: INFO - Timeout +05-Jul-19 15:43:39: INFO - Timeout +05-Jul-19 15:43:49: INFO - Timeout +05-Jul-19 15:43:59: INFO - Timeout +05-Jul-19 15:44:09: INFO - Timeout +05-Jul-19 15:44:19: INFO - Timeout +05-Jul-19 15:44:29: INFO - Timeout +05-Jul-19 15:44:40: INFO - Timeout +05-Jul-19 15:44:50: INFO - Timeout +05-Jul-19 15:45:00: INFO - Timeout +05-Jul-19 15:45:10: INFO - Timeout +05-Jul-19 15:45:20: INFO - Timeout +05-Jul-19 15:45:30: INFO - Timeout +05-Jul-19 15:45:40: INFO - Timeout +05-Jul-19 15:45:50: INFO - Timeout +05-Jul-19 15:46:00: INFO - Timeout +05-Jul-19 15:46:10: INFO - Timeout +05-Jul-19 15:46:20: INFO - Timeout +05-Jul-19 15:46:30: INFO - Timeout +05-Jul-19 15:46:40: INFO - Timeout +05-Jul-19 15:46:50: INFO - Timeout +05-Jul-19 15:47:00: INFO - Timeout +05-Jul-19 15:47:10: INFO - Timeout +05-Jul-19 15:47:20: INFO - Timeout +05-Jul-19 15:47:30: INFO - Timeout +05-Jul-19 15:47:40: INFO - Timeout +05-Jul-19 15:47:50: INFO - Timeout +05-Jul-19 15:48:00: INFO - Timeout +05-Jul-19 15:48:10: INFO - Timeout +05-Jul-19 15:48:20: INFO - Timeout +05-Jul-19 15:48:30: INFO - Timeout +05-Jul-19 15:48:40: INFO - Timeout +05-Jul-19 15:48:50: INFO - Timeout +05-Jul-19 15:49:00: INFO - Timeout +05-Jul-19 15:49:10: INFO - Timeout +05-Jul-19 15:49:20: INFO - Timeout +05-Jul-19 15:49:30: INFO - Timeout +05-Jul-19 15:49:40: INFO - Timeout +05-Jul-19 15:49:50: INFO - Timeout +05-Jul-19 15:50:00: INFO - Timeout +05-Jul-19 15:50:10: INFO - Timeout +05-Jul-19 15:50:20: INFO - Timeout +05-Jul-19 15:50:30: INFO - Timeout +05-Jul-19 15:50:40: INFO - Timeout +05-Jul-19 15:50:50: INFO - Timeout +05-Jul-19 15:51:00: INFO - Timeout +05-Jul-19 15:51:10: INFO - Timeout +05-Jul-19 15:51:20: INFO - Timeout +05-Jul-19 15:51:30: INFO - Timeout +05-Jul-19 15:51:40: INFO - Timeout +05-Jul-19 15:51:50: INFO - Timeout +05-Jul-19 15:52:00: INFO - Timeout +05-Jul-19 15:52:10: INFO - Timeout +05-Jul-19 15:52:20: INFO - Timeout +05-Jul-19 15:52:30: INFO - Timeout +05-Jul-19 15:52:40: INFO - Timeout +05-Jul-19 15:52:50: INFO - Timeout +05-Jul-19 15:53:00: INFO - Timeout +05-Jul-19 15:53:10: INFO - Timeout +05-Jul-19 15:53:20: INFO - Timeout +05-Jul-19 15:53:30: INFO - Timeout +05-Jul-19 15:53:40: INFO - Timeout +05-Jul-19 15:53:50: INFO - Timeout +05-Jul-19 15:54:00: INFO - Timeout +05-Jul-19 15:54:10: INFO - Timeout +05-Jul-19 15:54:20: INFO - Timeout +05-Jul-19 15:54:30: INFO - Timeout +05-Jul-19 15:54:40: INFO - Timeout +05-Jul-19 15:54:50: INFO - Timeout +05-Jul-19 15:55:00: INFO - Timeout +05-Jul-19 15:55:10: INFO - Timeout +05-Jul-19 15:55:20: INFO - Timeout +05-Jul-19 15:55:30: INFO - Timeout +05-Jul-19 15:55:40: INFO - Timeout +05-Jul-19 15:55:50: INFO - Timeout +05-Jul-19 15:56:00: INFO - Timeout +05-Jul-19 15:56:10: INFO - Timeout +05-Jul-19 15:56:20: INFO - Timeout +05-Jul-19 15:56:30: INFO - Timeout +05-Jul-19 15:56:40: INFO - Timeout +05-Jul-19 15:56:50: INFO - Timeout +05-Jul-19 15:57:00: INFO - Timeout +05-Jul-19 15:57:10: INFO - Timeout +05-Jul-19 15:57:20: INFO - Timeout +05-Jul-19 15:57:30: INFO - Timeout +05-Jul-19 15:57:40: INFO - Timeout +05-Jul-19 15:57:50: INFO - Timeout +05-Jul-19 15:58:00: INFO - Timeout +05-Jul-19 15:58:10: INFO - Timeout +05-Jul-19 15:58:20: INFO - Timeout +05-Jul-19 15:58:30: INFO - Timeout +05-Jul-19 15:58:40: INFO - Timeout +05-Jul-19 15:58:50: INFO - Timeout +05-Jul-19 15:59:00: INFO - Timeout +05-Jul-19 15:59:10: INFO - Timeout +05-Jul-19 15:59:20: INFO - Timeout +05-Jul-19 15:59:30: INFO - Timeout +05-Jul-19 15:59:40: INFO - Timeout +05-Jul-19 15:59:50: INFO - Timeout +05-Jul-19 16:00:00: INFO - Timeout +05-Jul-19 16:00:10: INFO - Timeout +05-Jul-19 16:00:20: INFO - Timeout +05-Jul-19 16:00:30: INFO - Timeout +05-Jul-19 16:00:40: INFO - Timeout +05-Jul-19 16:00:50: INFO - Timeout +05-Jul-19 16:01:00: INFO - Timeout +05-Jul-19 16:01:10: INFO - Timeout +05-Jul-19 16:01:20: INFO - Timeout +05-Jul-19 16:01:30: INFO - Timeout +05-Jul-19 16:01:40: INFO - Timeout +05-Jul-19 16:01:50: INFO - Timeout +05-Jul-19 16:02:00: INFO - Timeout +05-Jul-19 16:02:10: INFO - Timeout +05-Jul-19 16:02:20: INFO - Timeout +05-Jul-19 16:02:30: INFO - Timeout +05-Jul-19 16:02:40: INFO - Timeout +05-Jul-19 16:02:50: INFO - Timeout +05-Jul-19 16:03:00: INFO - Timeout +05-Jul-19 16:03:10: INFO - Timeout +05-Jul-19 16:03:20: INFO - Timeout +05-Jul-19 16:03:30: INFO - Timeout +05-Jul-19 16:03:40: INFO - Timeout +05-Jul-19 16:03:50: INFO - Timeout +05-Jul-19 16:04:00: INFO - Timeout +05-Jul-19 16:04:10: INFO - Timeout +05-Jul-19 16:04:20: INFO - Timeout +05-Jul-19 16:04:30: INFO - Timeout +05-Jul-19 16:04:40: INFO - Timeout +05-Jul-19 16:04:50: INFO - Timeout +05-Jul-19 16:05:00: INFO - Timeout +05-Jul-19 16:05:10: INFO - Timeout +05-Jul-19 16:05:20: INFO - Timeout +05-Jul-19 16:05:30: INFO - Timeout +05-Jul-19 16:05:40: INFO - Timeout +05-Jul-19 16:05:50: INFO - Timeout +05-Jul-19 16:06:00: INFO - Timeout +05-Jul-19 16:06:10: INFO - Timeout +05-Jul-19 16:06:20: INFO - Timeout +05-Jul-19 16:06:30: INFO - Timeout +05-Jul-19 16:06:40: INFO - Timeout +05-Jul-19 16:06:50: INFO - Timeout +05-Jul-19 16:07:00: INFO - Timeout +05-Jul-19 16:07:10: INFO - Timeout +05-Jul-19 16:07:20: INFO - Timeout +05-Jul-19 16:07:30: INFO - Timeout +05-Jul-19 16:07:40: INFO - Timeout +05-Jul-19 16:07:50: INFO - Timeout +05-Jul-19 16:08:00: INFO - Timeout +05-Jul-19 16:08:10: INFO - Timeout +05-Jul-19 16:08:20: INFO - Timeout +05-Jul-19 16:08:30: INFO - Timeout +05-Jul-19 16:08:40: INFO - Timeout +05-Jul-19 16:08:58: INFO - Timeout +05-Jul-19 16:08:58: DEBUG - None /v1/vm/726ce8bdd289436da4a647dac79fd027 +05-Jul-19 16:08:58: INFO - /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running but is said to be running +05-Jul-19 16:08:58: INFO - Updating /v1/vm/726ce8bdd289436da4a647dac79fd027 status to KILLED +05-Jul-19 16:08:58: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +05-Jul-19 16:08:58: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +05-Jul-19 16:08:58: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +05-Jul-19 16:08:58: DEBUG - None /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:08:58: INFO - /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 is not running but is said to be running +05-Jul-19 16:08:58: INFO - Updating /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 status to KILLED +05-Jul-19 16:09:08: INFO - Timeout +05-Jul-19 16:09:11: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:09:11: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:09:11: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpr8vzdoza/qemu-25371-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 16:09:11: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:09:11: DEBUG - <<< {'return': {}} +05-Jul-19 16:09:11: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:09:11: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:09:11: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:09:11: INFO - Unknown Request +05-Jul-19 16:09:11: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:09:18: INFO - Timeout +05-Jul-19 16:09:28: INFO - Timeout +05-Jul-19 16:09:38: INFO - Timeout +05-Jul-19 16:09:48: INFO - Timeout +05-Jul-19 16:09:58: INFO - Timeout +05-Jul-19 16:10:08: INFO - Timeout +05-Jul-19 16:10:18: INFO - Timeout +05-Jul-19 16:10:28: INFO - Timeout +05-Jul-19 16:10:38: INFO - Timeout +05-Jul-19 16:10:48: INFO - Timeout +05-Jul-19 16:10:58: INFO - Timeout +05-Jul-19 16:11:08: INFO - Timeout +05-Jul-19 16:11:18: INFO - Timeout +05-Jul-19 16:11:28: INFO - Timeout +05-Jul-19 16:11:38: INFO - Timeout +05-Jul-19 16:11:48: INFO - Timeout +05-Jul-19 16:11:58: INFO - Timeout +05-Jul-19 16:12:08: INFO - Timeout +05-Jul-19 16:12:18: INFO - Timeout +05-Jul-19 16:12:28: INFO - Timeout +05-Jul-19 16:12:38: INFO - Timeout +05-Jul-19 16:12:48: INFO - Timeout +05-Jul-19 16:12:58: INFO - Timeout +05-Jul-19 16:13:08: INFO - Timeout +05-Jul-19 16:13:18: INFO - Timeout +05-Jul-19 16:13:28: INFO - Timeout +05-Jul-19 16:13:38: INFO - Timeout +05-Jul-19 16:13:48: INFO - Timeout +05-Jul-19 16:13:58: INFO - Timeout +05-Jul-19 16:14:09: INFO - Timeout +05-Jul-19 16:14:19: INFO - Timeout +05-Jul-19 16:14:29: INFO - Timeout +05-Jul-19 16:14:39: INFO - Timeout +05-Jul-19 16:14:49: INFO - Timeout +05-Jul-19 16:14:59: INFO - Timeout +05-Jul-19 16:15:09: INFO - Timeout +05-Jul-19 16:15:19: INFO - Timeout +05-Jul-19 16:15:29: INFO - Timeout +05-Jul-19 16:15:39: INFO - Timeout +05-Jul-19 16:15:49: INFO - Timeout +05-Jul-19 16:15:59: INFO - Timeout +05-Jul-19 16:16:06: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:16:06: INFO - Unknown Request +05-Jul-19 16:16:06: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:16:09: INFO - Timeout +05-Jul-19 16:16:19: INFO - Timeout +05-Jul-19 16:16:29: INFO - Timeout +05-Jul-19 16:16:39: INFO - Timeout +05-Jul-19 16:16:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:16:52: INFO - Cannot shutdown non-running Vm /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:16:52: INFO - Running VMs [] +05-Jul-19 16:16:58: INFO - Timeout +05-Jul-19 16:17:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:08: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:08: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpa4v_u7pp/qemu-26559-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 16:17:08: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:17:08: DEBUG - <<< {'return': {}} +05-Jul-19 16:17:08: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:08: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:08: INFO - Unknown Request +05-Jul-19 16:17:08: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:08: INFO - Timeout +05-Jul-19 16:17:18: INFO - Timeout +05-Jul-19 16:17:20: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:20: INFO - Shutting down VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:20: DEBUG - >>> {'execute': 'quit'} +05-Jul-19 16:17:20: DEBUG - <<< {'return': {}} +05-Jul-19 16:17:20: INFO - VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 shutdown +05-Jul-19 16:17:20: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:20: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'STOPPED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:20: INFO - Unknown Request +05-Jul-19 16:17:20: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:28: INFO - Timeout +05-Jul-19 16:17:38: INFO - Timeout +05-Jul-19 16:17:46: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:46: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:46: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp1tpvtrpn/qemu-26559-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :1' +05-Jul-19 16:17:46: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:17:46: DEBUG - <<< {'return': {}} +05-Jul-19 16:17:46: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:46: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:46: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:46: INFO - Unknown Request +05-Jul-19 16:17:46: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:48: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:48: INFO - Shutting down VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:48: INFO - VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 shutdown +05-Jul-19 16:17:48: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:48: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'STOPPED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:48: INFO - Unknown Request +05-Jul-19 16:17:48: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:48: INFO - Timeout +05-Jul-19 16:17:50: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:50: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:50: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp8ww22cyl/qemu-26559-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :2' +05-Jul-19 16:17:50: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:17:50: DEBUG - <<< None +05-Jul-19 16:17:50: DEBUG - Error launching VM +05-Jul-19 16:17:50: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp8ww22cyl/qemu-26559-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :2' +05-Jul-19 16:17:50: DEBUG - Output: 'WARNING: Image format was not specified for \'/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw\' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the \'raw\' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Failed to get "write" lock\nIs another process using the image [/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw]?\n' +05-Jul-19 16:17:50: INFO - Machine Error Occurred on /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:50: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:50: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'KILLED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:50: INFO - Unknown Request +05-Jul-19 16:17:50: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:52: INFO - Shutting down VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:17:52: INFO - VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 shutdown +05-Jul-19 16:17:52: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'STOPPED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:17:52: INFO - Unknown Request +05-Jul-19 16:17:52: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:17:58: INFO - Timeout +05-Jul-19 16:18:08: INFO - Timeout +05-Jul-19 16:18:18: INFO - Timeout +05-Jul-19 16:18:28: INFO - Timeout +05-Jul-19 16:18:38: INFO - Timeout +05-Jul-19 16:18:48: ERROR - Invalid JSON +05-Jul-19 16:18:48: INFO - Timeout +05-Jul-19 16:18:58: INFO - Timeout +05-Jul-19 16:19:08: INFO - Timeout +05-Jul-19 16:19:15: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:19:15: DEBUG - >>> {'execute': 'cont'} +05-Jul-19 16:21:47: INFO - Timeout +05-Jul-19 16:21:48: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:21:48: INFO - Cannot resume VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 that is not running +05-Jul-19 16:21:48: INFO - Running VMs [] +05-Jul-19 16:21:57: INFO - Timeout +05-Jul-19 16:22:07: INFO - Timeout +05-Jul-19 16:22:17: INFO - Timeout +05-Jul-19 16:22:27: INFO - Timeout +05-Jul-19 16:22:37: INFO - Timeout +05-Jul-19 16:22:47: INFO - Timeout +05-Jul-19 16:22:57: INFO - Timeout +05-Jul-19 16:23:07: INFO - Timeout +05-Jul-19 16:23:17: INFO - Timeout +05-Jul-19 16:23:27: INFO - Timeout +05-Jul-19 16:23:37: INFO - Timeout +05-Jul-19 16:23:47: INFO - Timeout +05-Jul-19 16:23:57: INFO - Timeout +05-Jul-19 16:24:07: INFO - Timeout +05-Jul-19 16:24:17: INFO - Timeout +05-Jul-19 16:24:27: INFO - Timeout +05-Jul-19 16:24:37: INFO - Timeout +05-Jul-19 16:24:54: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:24:54: INFO - Cannot resume VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 that is not running +05-Jul-19 16:24:54: INFO - Running VMs [] +05-Jul-19 16:24:54: INFO - Timeout +05-Jul-19 16:25:04: INFO - Timeout +05-Jul-19 16:25:14: INFO - Timeout +05-Jul-19 16:25:24: INFO - Timeout +05-Jul-19 16:25:34: INFO - Timeout +05-Jul-19 16:25:44: INFO - Timeout +05-Jul-19 16:25:54: INFO - Timeout +05-Jul-19 16:26:04: INFO - Timeout +05-Jul-19 16:26:14: INFO - Timeout +05-Jul-19 16:26:24: INFO - Timeout +05-Jul-19 16:26:34: INFO - Timeout +05-Jul-19 16:26:44: INFO - Timeout +05-Jul-19 16:26:54: INFO - Timeout +05-Jul-19 16:27:04: INFO - Timeout +05-Jul-19 16:27:14: INFO - Timeout +05-Jul-19 16:27:24: INFO - Timeout +05-Jul-19 16:27:34: INFO - Timeout +05-Jul-19 16:27:44: INFO - Timeout +05-Jul-19 16:27:54: INFO - Timeout +05-Jul-19 16:28:04: INFO - Timeout +05-Jul-19 16:28:14: INFO - Timeout +05-Jul-19 16:28:24: INFO - Timeout +05-Jul-19 16:28:34: INFO - Timeout +05-Jul-19 16:28:44: INFO - Timeout +05-Jul-19 16:28:54: INFO - Timeout +05-Jul-19 16:29:04: INFO - Timeout +05-Jul-19 16:29:14: INFO - Timeout +05-Jul-19 16:29:24: INFO - Timeout +05-Jul-19 16:29:34: INFO - Timeout +05-Jul-19 16:29:44: INFO - Timeout +05-Jul-19 16:29:54: INFO - Timeout +05-Jul-19 16:30:04: INFO - Timeout +05-Jul-19 16:30:14: INFO - Timeout +05-Jul-19 16:30:24: INFO - Timeout +05-Jul-19 16:30:34: INFO - Timeout +05-Jul-19 16:30:44: INFO - Timeout +05-Jul-19 16:30:54: INFO - Timeout +05-Jul-19 16:31:04: INFO - Timeout +05-Jul-19 16:31:14: INFO - Timeout +05-Jul-19 16:31:24: INFO - Timeout +05-Jul-19 16:31:34: INFO - Timeout +05-Jul-19 16:31:44: INFO - Timeout +05-Jul-19 16:31:54: INFO - Timeout +05-Jul-19 16:32:04: INFO - Timeout +05-Jul-19 16:32:14: INFO - Timeout +05-Jul-19 16:32:24: INFO - Timeout +05-Jul-19 16:32:34: INFO - Timeout +05-Jul-19 16:32:44: INFO - Timeout +05-Jul-19 16:32:54: INFO - Timeout +05-Jul-19 16:33:04: INFO - Timeout +05-Jul-19 16:33:14: INFO - Timeout +05-Jul-19 16:33:24: INFO - Timeout +05-Jul-19 16:33:34: INFO - Timeout +05-Jul-19 16:33:44: INFO - Timeout +05-Jul-19 16:33:54: INFO - Timeout +05-Jul-19 16:34:04: INFO - Timeout +05-Jul-19 16:34:14: INFO - Timeout +05-Jul-19 16:34:24: INFO - Timeout +05-Jul-19 16:34:34: INFO - Timeout +05-Jul-19 16:34:44: INFO - Timeout +05-Jul-19 16:34:54: INFO - Timeout +05-Jul-19 16:35:04: INFO - Timeout +05-Jul-19 16:35:14: INFO - Timeout +05-Jul-19 16:35:24: INFO - Timeout +05-Jul-19 16:35:34: INFO - Timeout +05-Jul-19 16:35:44: INFO - Timeout +05-Jul-19 16:35:54: INFO - Timeout +05-Jul-19 16:36:04: INFO - Timeout +05-Jul-19 16:36:14: INFO - Timeout +05-Jul-19 16:36:24: INFO - Timeout +05-Jul-19 16:36:34: INFO - Timeout +05-Jul-19 16:36:44: INFO - Timeout +05-Jul-19 16:38:34: INFO - Timeout +05-Jul-19 16:38:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:38:34: INFO - Cannot resume VM /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 that is not running +05-Jul-19 16:38:34: INFO - Running VMs [] +05-Jul-19 16:38:44: INFO - Timeout +05-Jul-19 16:38:49: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:38:49: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:38:49: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpp0fb1hjb/qemu-27221-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 16:38:49: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:38:49: DEBUG - <<< None +05-Jul-19 16:38:49: DEBUG - Error launching VM +05-Jul-19 16:38:49: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpp0fb1hjb/qemu-27221-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 16:38:49: DEBUG - Output: 'WARNING: Image format was not specified for \'/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw\' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the \'raw\' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Failed to get "write" lock\nIs another process using the image [/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw]?\n' +05-Jul-19 16:38:49: INFO - Machine Error Occurred on /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:38:49: INFO - Running VMs [] +05-Jul-19 16:38:49: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'KILLED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:38:49: INFO - Unknown Request +05-Jul-19 16:38:49: INFO - Running VMs [] +05-Jul-19 16:38:54: INFO - Timeout +05-Jul-19 16:39:04: INFO - Timeout +05-Jul-19 16:39:14: INFO - Timeout +05-Jul-19 16:39:30: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:30: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:39:30: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp_ew2ljz3/qemu-27263-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +05-Jul-19 16:39:30: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:39:30: DEBUG - <<< {'return': {}} +05-Jul-19 16:39:30: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:39:30: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:30: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:30: INFO - Unknown Request +05-Jul-19 16:39:30: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:34: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:39:34: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmph48ehtfl/qemu-27263-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :1' +05-Jul-19 16:39:34: DEBUG - >>> {'execute': 'qmp_capabilities'} +05-Jul-19 16:39:34: DEBUG - <<< None +05-Jul-19 16:39:34: DEBUG - Error launching VM +05-Jul-19 16:39:34: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmph48ehtfl/qemu-27263-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :1' +05-Jul-19 16:39:34: DEBUG - Output: 'WARNING: Image format was not specified for \'/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw\' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the \'raw\' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Failed to get "write" lock\nIs another process using the image [/var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw]?\n' +05-Jul-19 16:39:34: INFO - Machine Error Occurred on /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +05-Jul-19 16:39:34: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'KILLED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:34: INFO - Unknown Request +05-Jul-19 16:39:34: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:34: INFO - Timeout +05-Jul-19 16:39:37: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:37: DEBUG - >>> {'execute': 'cont'} +05-Jul-19 16:39:37: DEBUG - <<< {'return': {}} +05-Jul-19 16:39:37: DEBUG - >>> {'execute': 'query-status'} +05-Jul-19 16:39:37: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +05-Jul-19 16:39:37: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:37: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:39:37: INFO - Unknown Request +05-Jul-19 16:39:37: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:39:44: INFO - Timeout +05-Jul-19 16:39:54: INFO - Timeout +05-Jul-19 16:40:04: INFO - Timeout +05-Jul-19 16:40:14: INFO - Timeout +05-Jul-19 16:40:24: INFO - Timeout +05-Jul-19 16:40:34: INFO - Timeout +05-Jul-19 16:40:44: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:40:44: DEBUG - >>> {'execute': 'stop'} +05-Jul-19 16:40:44: DEBUG - <<< {'timestamp': {'seconds': 1562326833, 'microseconds': 820091}, 'event': 'RTC_CHANGE', 'data': {'offset': 0}} +05-Jul-19 16:40:44: DEBUG - <<< {'timestamp': {'seconds': 1562326844, 'microseconds': 76554}, 'event': 'STOP'} +05-Jul-19 16:40:44: DEBUG - <<< {'return': {}} +05-Jul-19 16:40:44: DEBUG - >>> {'execute': 'query-status'} +05-Jul-19 16:40:44: DEBUG - <<< {'return': {'status': 'paused', 'singlestep': False, 'running': False}} +05-Jul-19 16:40:44: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:40:44: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'SUSPENDED', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:40:44: INFO - Unknown Request +05-Jul-19 16:40:44: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:40:44: INFO - Timeout +05-Jul-19 16:40:54: INFO - Timeout +05-Jul-19 16:41:04: INFO - Timeout +05-Jul-19 16:41:14: INFO - Timeout +05-Jul-19 16:41:24: INFO - Timeout +05-Jul-19 16:41:34: INFO - Timeout +05-Jul-19 16:41:44: INFO - Timeout +05-Jul-19 16:41:54: INFO - Timeout +05-Jul-19 16:42:04: INFO - Timeout +05-Jul-19 16:42:14: INFO - Timeout +05-Jul-19 16:42:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:19: DEBUG - >>> {'execute': 'cont'} +05-Jul-19 16:42:19: DEBUG - <<< {'timestamp': {'seconds': 1562326939, 'microseconds': 689521}, 'event': 'RESUME'} +05-Jul-19 16:42:19: DEBUG - <<< {'return': {}} +05-Jul-19 16:42:19: DEBUG - >>> {'execute': 'query-status'} +05-Jul-19 16:42:19: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +05-Jul-19 16:42:19: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:19: INFO - Unknown Request +05-Jul-19 16:42:19: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:22: DEBUG - >>> {'execute': 'cont'} +05-Jul-19 16:42:22: DEBUG - <<< {'return': {}} +05-Jul-19 16:42:22: DEBUG - >>> {'execute': 'query-status'} +05-Jul-19 16:42:22: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +05-Jul-19 16:42:22: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:22: INFO - Unknown Request +05-Jul-19 16:42:22: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:23: DEBUG - >>> {'execute': 'cont'} +05-Jul-19 16:42:23: DEBUG - <<< {'return': {}} +05-Jul-19 16:42:23: DEBUG - >>> {'execute': 'query-status'} +05-Jul-19 16:42:23: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +05-Jul-19 16:42:23: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +05-Jul-19 16:42:23: INFO - Unknown Request +05-Jul-19 16:42:23: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +05-Jul-19 16:42:24: INFO - Timeout +05-Jul-19 16:42:34: INFO - Timeout +05-Jul-19 16:42:44: INFO - Timeout +06-Jul-19 19:17:16: INFO - Timeout +06-Jul-19 19:17:16: DEBUG - None /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +06-Jul-19 19:17:16: INFO - /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 is not running but is said to be running +06-Jul-19 19:17:16: INFO - Updating /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 status to KILLED +06-Jul-19 19:17:26: INFO - Timeout +06-Jul-19 19:17:36: INFO - Timeout +06-Jul-19 19:17:46: INFO - Timeout +06-Jul-19 19:17:56: INFO - Timeout +06-Jul-19 19:18:06: INFO - Timeout +06-Jul-19 19:18:16: INFO - Timeout +06-Jul-19 19:18:26: INFO - Timeout +06-Jul-19 19:18:36: INFO - Timeout +06-Jul-19 19:18:46: INFO - Timeout +06-Jul-19 19:18:56: INFO - Timeout +06-Jul-19 19:19:06: INFO - Timeout +06-Jul-19 19:19:16: INFO - Timeout +06-Jul-19 19:19:26: INFO - Timeout +06-Jul-19 19:19:36: INFO - Timeout +06-Jul-19 19:19:46: INFO - Timeout +06-Jul-19 19:19:56: INFO - Timeout +06-Jul-19 19:20:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:20:00: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +06-Jul-19 19:20:00: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp54w6aert/qemu-13411-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +06-Jul-19 19:20:00: DEBUG - >>> {'execute': 'qmp_capabilities'} +06-Jul-19 19:20:00: DEBUG - <<< {'return': {}} +06-Jul-19 19:20:00: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +06-Jul-19 19:20:00: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +06-Jul-19 19:20:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:20:00: INFO - Unknown Request +06-Jul-19 19:20:00: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +06-Jul-19 19:20:06: INFO - Timeout +06-Jul-19 19:20:16: INFO - Timeout +06-Jul-19 19:20:26: INFO - Timeout +06-Jul-19 19:20:36: INFO - Timeout +06-Jul-19 19:20:42: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:20:42: DEBUG - >>> {'execute': 'query-status'} +06-Jul-19 19:20:42: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +06-Jul-19 19:20:42: DEBUG - VM Status Check - {'status': 'running', 'singlestep': False, 'running': True} +06-Jul-19 19:20:42: INFO - Shutting down VM /v1/vm/726ce8bdd289436da4a647dac79fd027 +06-Jul-19 19:20:42: DEBUG - >>> {'execute': 'quit'} +06-Jul-19 19:20:42: DEBUG - <<< {'return': {}} +06-Jul-19 19:20:42: INFO - VM /v1/vm/726ce8bdd289436da4a647dac79fd027 shutdown successfully +06-Jul-19 19:20:42: INFO - Running VMs [] +06-Jul-19 19:20:42: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'STOPPED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:20:42: INFO - Unknown Request +06-Jul-19 19:20:42: INFO - Running VMs [] +06-Jul-19 19:20:46: INFO - Timeout +06-Jul-19 19:20:56: INFO - Timeout +06-Jul-19 19:21:06: INFO - Timeout +06-Jul-19 19:21:16: INFO - Timeout +06-Jul-19 19:21:26: INFO - Timeout +06-Jul-19 19:21:36: INFO - Timeout +06-Jul-19 19:21:46: INFO - Timeout +06-Jul-19 19:21:56: INFO - Timeout +06-Jul-19 19:22:06: INFO - Timeout +06-Jul-19 19:22:16: INFO - Timeout +06-Jul-19 19:22:26: INFO - Timeout +06-Jul-19 19:22:36: INFO - Timeout +06-Jul-19 19:22:42: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:22:42: INFO - resume_vm failed because VM /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running +06-Jul-19 19:22:42: INFO - Running VMs [] +06-Jul-19 19:22:46: INFO - Timeout +06-Jul-19 19:22:56: INFO - Timeout +06-Jul-19 19:23:06: INFO - Timeout +06-Jul-19 19:23:16: INFO - Timeout +06-Jul-19 19:23:26: INFO - Timeout +06-Jul-19 19:23:36: INFO - Timeout +06-Jul-19 19:23:46: INFO - Timeout +06-Jul-19 19:23:56: INFO - Timeout +06-Jul-19 19:24:06: INFO - Timeout +06-Jul-19 19:24:16: INFO - Timeout +06-Jul-19 19:24:26: INFO - Timeout +06-Jul-19 19:24:36: INFO - Timeout +06-Jul-19 19:24:46: INFO - Timeout +06-Jul-19 19:24:56: INFO - Timeout +06-Jul-19 19:25:04: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:25:04: INFO - suspend_vm failed because VM /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running +06-Jul-19 19:25:04: INFO - Running VMs [] +06-Jul-19 19:25:06: INFO - Timeout +06-Jul-19 19:25:16: INFO - Timeout +06-Jul-19 19:25:26: INFO - Timeout +06-Jul-19 19:25:36: INFO - Timeout +06-Jul-19 19:25:46: INFO - Timeout +06-Jul-19 19:25:56: INFO - Timeout +06-Jul-19 19:26:06: INFO - Timeout +06-Jul-19 19:26:16: INFO - Timeout +06-Jul-19 19:26:26: INFO - Timeout +06-Jul-19 19:26:36: INFO - Timeout +06-Jul-19 19:26:46: INFO - Timeout +06-Jul-19 19:26:56: INFO - Timeout +06-Jul-19 19:27:06: INFO - Timeout +06-Jul-19 19:27:16: INFO - Timeout +06-Jul-19 19:27:26: INFO - Timeout +06-Jul-19 19:27:36: INFO - Timeout +06-Jul-19 19:27:46: INFO - Timeout +06-Jul-19 19:27:56: INFO - Timeout +06-Jul-19 19:28:06: INFO - Timeout +06-Jul-19 19:28:16: INFO - Timeout +06-Jul-19 19:28:26: INFO - Timeout +06-Jul-19 19:28:36: INFO - Timeout +06-Jul-19 19:28:46: INFO - Timeout +06-Jul-19 19:28:56: INFO - Timeout +06-Jul-19 19:29:06: INFO - Timeout +06-Jul-19 19:29:16: INFO - Timeout +06-Jul-19 19:29:26: INFO - Timeout +06-Jul-19 19:29:43: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:29:43: INFO - suspend_vm failed because VM /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running +06-Jul-19 19:29:43: INFO - Running VMs [] +06-Jul-19 19:29:47: INFO - Timeout +06-Jul-19 19:29:51: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:29:51: INFO - resume_vm failed because VM /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running +06-Jul-19 19:29:51: INFO - Running VMs [] +06-Jul-19 19:29:57: INFO - Timeout +06-Jul-19 19:29:58: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:29:58: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +06-Jul-19 19:29:58: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp75qs0v2y/qemu-13815-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +06-Jul-19 19:29:58: DEBUG - >>> {'execute': 'qmp_capabilities'} +06-Jul-19 19:29:58: DEBUG - <<< {'return': {}} +06-Jul-19 19:29:58: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +06-Jul-19 19:29:58: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +06-Jul-19 19:29:58: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:29:58: INFO - Unknown Request +06-Jul-19 19:29:58: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +06-Jul-19 19:30:07: INFO - Timeout +06-Jul-19 19:30:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:30:08: DEBUG - >>> {'execute': 'query-status'} +06-Jul-19 19:30:08: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +06-Jul-19 19:30:08: DEBUG - VM Status Check - {'status': 'running', 'singlestep': False, 'running': True} +06-Jul-19 19:30:08: DEBUG - >>> {'execute': 'quit'} +06-Jul-19 19:30:08: DEBUG - <<< {'return': {}} +06-Jul-19 19:30:08: INFO - VM /v1/vm/726ce8bdd289436da4a647dac79fd027 shutdown successfully +06-Jul-19 19:30:08: INFO - Running VMs [] +06-Jul-19 19:30:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'STOPPED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:30:08: INFO - Unknown Request +06-Jul-19 19:30:08: INFO - Running VMs [] +06-Jul-19 19:30:17: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:30:17: INFO - shutdown_vm failed because VM /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running +06-Jul-19 19:30:17: INFO - Running VMs [] +06-Jul-19 19:30:17: INFO - Timeout +06-Jul-19 19:30:27: INFO - Timeout +06-Jul-19 19:30:37: INFO - Timeout +06-Jul-19 19:30:47: INFO - Timeout +06-Jul-19 19:30:50: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:30:50: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +06-Jul-19 19:30:50: INFO - Running VMs [] +06-Jul-19 19:30:57: INFO - Timeout +06-Jul-19 19:31:07: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:31:07: INFO - resume_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +06-Jul-19 19:31:07: INFO - Running VMs [] +06-Jul-19 19:31:07: INFO - Timeout +06-Jul-19 19:31:17: INFO - Timeout +06-Jul-19 19:31:27: INFO - Timeout +06-Jul-19 19:31:37: INFO - Timeout +06-Jul-19 19:31:47: INFO - Timeout +06-Jul-19 19:31:57: INFO - Timeout +06-Jul-19 19:32:07: INFO - Timeout +06-Jul-19 19:32:17: INFO - Timeout +06-Jul-19 19:32:27: INFO - Timeout +06-Jul-19 19:32:37: INFO - Timeout +06-Jul-19 19:32:41: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:32:41: INFO - resume_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +06-Jul-19 19:32:41: INFO - Running VMs [] +06-Jul-19 19:32:48: INFO - Timeout +06-Jul-19 19:32:55: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:32:55: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +06-Jul-19 19:32:55: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +06-Jul-19 19:32:55: INFO - Running VMs [] +06-Jul-19 19:32:55: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +06-Jul-19 19:32:55: INFO - Unknown Request +06-Jul-19 19:32:55: INFO - Running VMs [] +06-Jul-19 19:32:58: INFO - Timeout +06-Jul-19 19:33:08: INFO - Timeout +06-Jul-19 19:33:18: INFO - Timeout +06-Jul-19 19:33:28: INFO - Timeout +06-Jul-19 19:33:38: INFO - Timeout +06-Jul-19 19:33:48: INFO - Timeout +06-Jul-19 19:33:58: INFO - Timeout +06-Jul-19 19:34:08: INFO - Timeout +06-Jul-19 19:34:18: INFO - Timeout +06-Jul-19 19:34:28: INFO - Timeout +06-Jul-19 19:34:38: INFO - Timeout +06-Jul-19 19:34:48: INFO - Timeout +06-Jul-19 19:34:58: INFO - Timeout +06-Jul-19 19:35:08: INFO - Timeout +06-Jul-19 19:35:18: INFO - Timeout +06-Jul-19 19:35:28: INFO - Timeout +06-Jul-19 19:35:38: INFO - Timeout +06-Jul-19 19:35:48: INFO - Timeout +06-Jul-19 19:35:58: INFO - Timeout +06-Jul-19 19:36:08: INFO - Timeout +06-Jul-19 19:36:18: INFO - Timeout +06-Jul-19 19:36:28: INFO - Timeout +06-Jul-19 19:36:38: INFO - Timeout +06-Jul-19 19:36:48: INFO - Timeout +06-Jul-19 19:36:58: INFO - Timeout +06-Jul-19 19:37:08: INFO - Timeout +06-Jul-19 19:37:18: INFO - Timeout +06-Jul-19 19:37:28: INFO - Timeout +06-Jul-19 19:37:38: INFO - Timeout +06-Jul-19 19:37:48: INFO - Timeout +06-Jul-19 19:37:58: INFO - Timeout +06-Jul-19 19:38:08: INFO - Timeout +06-Jul-19 19:38:18: INFO - Timeout +06-Jul-19 19:38:28: INFO - Timeout +06-Jul-19 19:38:38: INFO - Timeout +06-Jul-19 19:38:48: INFO - Timeout +06-Jul-19 19:38:58: INFO - Timeout +06-Jul-19 19:39:08: INFO - Timeout +06-Jul-19 19:39:18: INFO - Timeout +06-Jul-19 19:39:28: INFO - Timeout +06-Jul-19 19:39:38: INFO - Timeout +06-Jul-19 19:39:48: INFO - Timeout +06-Jul-19 19:39:58: INFO - Timeout +06-Jul-19 19:40:08: INFO - Timeout +06-Jul-19 19:40:18: INFO - Timeout +06-Jul-19 19:40:28: INFO - Timeout +06-Jul-19 19:40:38: INFO - Timeout +06-Jul-19 19:40:48: INFO - Timeout +06-Jul-19 19:40:58: INFO - Timeout +06-Jul-19 19:41:08: INFO - Timeout +06-Jul-19 19:41:18: INFO - Timeout +06-Jul-19 19:41:28: INFO - Timeout +06-Jul-19 19:41:38: INFO - Timeout +06-Jul-19 19:41:48: INFO - Timeout +06-Jul-19 19:41:58: INFO - Timeout +06-Jul-19 19:42:08: INFO - Timeout +06-Jul-19 19:42:18: INFO - Timeout +06-Jul-19 19:42:28: INFO - Timeout +06-Jul-19 19:42:38: INFO - Timeout +06-Jul-19 19:42:48: INFO - Timeout +06-Jul-19 19:42:58: INFO - Timeout +06-Jul-19 19:43:08: INFO - Timeout +06-Jul-19 19:43:18: INFO - Timeout +06-Jul-19 19:43:28: INFO - Timeout +06-Jul-19 19:43:38: INFO - Timeout +06-Jul-19 19:43:48: INFO - Timeout +06-Jul-19 19:43:58: INFO - Timeout +06-Jul-19 19:44:08: INFO - Timeout +06-Jul-19 19:44:18: INFO - Timeout +06-Jul-19 19:44:28: INFO - Timeout +06-Jul-19 19:44:38: INFO - Timeout +06-Jul-19 19:44:48: INFO - Timeout +06-Jul-19 19:44:58: INFO - Timeout +06-Jul-19 19:45:08: INFO - Timeout +06-Jul-19 19:45:18: INFO - Timeout +06-Jul-19 19:45:28: INFO - Timeout +06-Jul-19 19:45:38: INFO - Timeout +06-Jul-19 19:45:48: INFO - Timeout +06-Jul-19 19:45:58: INFO - Timeout +06-Jul-19 19:46:08: INFO - Timeout +06-Jul-19 19:46:18: INFO - Timeout +06-Jul-19 19:46:28: INFO - Timeout +06-Jul-19 19:46:38: INFO - Timeout +06-Jul-19 19:46:48: INFO - Timeout +06-Jul-19 19:46:58: INFO - Timeout +06-Jul-19 19:47:08: INFO - Timeout +06-Jul-19 19:47:18: INFO - Timeout +06-Jul-19 19:47:28: INFO - Timeout +06-Jul-19 19:47:38: INFO - Timeout +06-Jul-19 19:47:48: INFO - Timeout +06-Jul-19 19:47:58: INFO - Timeout +06-Jul-19 19:48:08: INFO - Timeout +06-Jul-19 19:48:18: INFO - Timeout +06-Jul-19 19:48:28: INFO - Timeout +06-Jul-19 19:48:38: INFO - Timeout +06-Jul-19 19:48:48: INFO - Timeout +06-Jul-19 19:48:58: INFO - Timeout +06-Jul-19 19:49:08: INFO - Timeout +06-Jul-19 19:49:18: INFO - Timeout +06-Jul-19 19:49:28: INFO - Timeout +06-Jul-19 19:49:38: INFO - Timeout +06-Jul-19 19:49:48: INFO - Timeout +06-Jul-19 19:49:58: INFO - Timeout +06-Jul-19 19:50:08: INFO - Timeout +06-Jul-19 19:50:18: INFO - Timeout +06-Jul-19 19:50:28: INFO - Timeout +06-Jul-19 19:50:38: INFO - Timeout +06-Jul-19 19:50:48: INFO - Timeout +06-Jul-19 19:50:58: INFO - Timeout +06-Jul-19 19:51:08: INFO - Timeout +06-Jul-19 19:51:18: INFO - Timeout +06-Jul-19 19:51:28: INFO - Timeout +06-Jul-19 19:51:39: INFO - Timeout +06-Jul-19 19:51:49: INFO - Timeout +06-Jul-19 19:51:59: INFO - Timeout +06-Jul-19 19:52:09: INFO - Timeout +06-Jul-19 19:52:19: INFO - Timeout +06-Jul-19 19:52:29: INFO - Timeout +06-Jul-19 19:52:39: INFO - Timeout +06-Jul-19 19:52:49: INFO - Timeout +06-Jul-19 19:52:59: INFO - Timeout +06-Jul-19 19:53:09: INFO - Timeout +06-Jul-19 19:53:19: INFO - Timeout +06-Jul-19 19:53:29: INFO - Timeout +06-Jul-19 19:53:39: INFO - Timeout +06-Jul-19 19:53:49: INFO - Timeout +06-Jul-19 19:53:59: INFO - Timeout +06-Jul-19 19:54:09: INFO - Timeout +06-Jul-19 19:54:19: INFO - Timeout +06-Jul-19 19:54:29: INFO - Timeout +06-Jul-19 19:54:39: INFO - Timeout +06-Jul-19 19:54:49: INFO - Timeout +06-Jul-19 19:54:59: INFO - Timeout +06-Jul-19 19:55:09: INFO - Timeout +06-Jul-19 19:55:19: INFO - Timeout +06-Jul-19 19:55:29: INFO - Timeout +06-Jul-19 19:55:39: INFO - Timeout +06-Jul-19 19:55:49: INFO - Timeout +06-Jul-19 19:55:59: INFO - Timeout +06-Jul-19 19:56:09: INFO - Timeout +06-Jul-19 19:56:19: INFO - Timeout +06-Jul-19 19:56:29: INFO - Timeout +06-Jul-19 19:56:39: INFO - Timeout +06-Jul-19 19:56:49: INFO - Timeout +06-Jul-19 19:56:59: INFO - Timeout +06-Jul-19 19:57:09: INFO - Timeout +06-Jul-19 19:57:19: INFO - Timeout +06-Jul-19 19:57:29: INFO - Timeout +06-Jul-19 19:57:39: INFO - Timeout +06-Jul-19 19:57:49: INFO - Timeout +06-Jul-19 19:57:59: INFO - Timeout +06-Jul-19 19:58:09: INFO - Timeout +06-Jul-19 19:58:19: INFO - Timeout +06-Jul-19 19:58:29: INFO - Timeout +06-Jul-19 19:58:39: INFO - Timeout +06-Jul-19 19:58:49: INFO - Timeout +06-Jul-19 19:58:59: INFO - Timeout +06-Jul-19 19:59:09: INFO - Timeout +06-Jul-19 19:59:19: INFO - Timeout +06-Jul-19 19:59:29: INFO - Timeout +06-Jul-19 19:59:39: INFO - Timeout +06-Jul-19 19:59:49: INFO - Timeout +06-Jul-19 19:59:59: INFO - Timeout +06-Jul-19 20:00:09: INFO - Timeout +06-Jul-19 20:00:19: INFO - Timeout +06-Jul-19 20:00:29: INFO - Timeout +06-Jul-19 20:00:39: INFO - Timeout +06-Jul-19 20:00:49: INFO - Timeout +06-Jul-19 20:00:59: INFO - Timeout +06-Jul-19 20:01:09: INFO - Timeout +06-Jul-19 20:01:19: INFO - Timeout +06-Jul-19 20:01:29: INFO - Timeout +06-Jul-19 20:01:39: INFO - Timeout +06-Jul-19 20:01:49: INFO - Timeout +06-Jul-19 20:01:59: INFO - Timeout +06-Jul-19 20:02:09: INFO - Timeout +06-Jul-19 20:02:19: INFO - Timeout +06-Jul-19 20:02:29: INFO - Timeout +06-Jul-19 20:02:39: INFO - Timeout +06-Jul-19 20:02:49: INFO - Timeout +06-Jul-19 20:02:59: INFO - Timeout +06-Jul-19 20:03:09: INFO - Timeout +06-Jul-19 20:03:19: INFO - Timeout +06-Jul-19 20:03:29: INFO - Timeout +06-Jul-19 20:03:39: INFO - Timeout +06-Jul-19 20:03:49: INFO - Timeout +06-Jul-19 20:03:59: INFO - Timeout +06-Jul-19 20:04:09: INFO - Timeout +06-Jul-19 20:04:19: INFO - Timeout +06-Jul-19 20:04:29: INFO - Timeout +06-Jul-19 20:04:39: INFO - Timeout +06-Jul-19 20:04:49: INFO - Timeout +06-Jul-19 20:04:59: INFO - Timeout +06-Jul-19 20:05:09: INFO - Timeout +06-Jul-19 20:05:19: INFO - Timeout +06-Jul-19 20:05:29: INFO - Timeout +06-Jul-19 20:05:39: INFO - Timeout +06-Jul-19 20:05:49: INFO - Timeout +06-Jul-19 20:05:59: INFO - Timeout +06-Jul-19 20:06:09: INFO - Timeout +06-Jul-19 20:06:19: INFO - Timeout +06-Jul-19 20:06:29: INFO - Timeout +06-Jul-19 20:06:39: INFO - Timeout +06-Jul-19 20:06:49: INFO - Timeout +06-Jul-19 20:06:59: INFO - Timeout +06-Jul-19 20:07:09: INFO - Timeout +06-Jul-19 20:07:19: INFO - Timeout +06-Jul-19 20:07:29: INFO - Timeout +06-Jul-19 20:07:39: INFO - Timeout +06-Jul-19 20:07:49: INFO - Timeout +06-Jul-19 20:07:59: INFO - Timeout +06-Jul-19 20:08:09: INFO - Timeout +06-Jul-19 20:08:19: INFO - Timeout +06-Jul-19 20:08:29: INFO - Timeout +06-Jul-19 20:08:39: INFO - Timeout +06-Jul-19 20:08:49: INFO - Timeout +06-Jul-19 20:08:59: INFO - Timeout +06-Jul-19 20:09:09: INFO - Timeout +06-Jul-19 20:09:19: INFO - Timeout +06-Jul-19 20:09:29: INFO - Timeout +06-Jul-19 20:09:39: INFO - Timeout +06-Jul-19 20:09:49: INFO - Timeout +06-Jul-19 20:09:59: INFO - Timeout +06-Jul-19 20:10:09: INFO - Timeout +06-Jul-19 20:10:19: INFO - Timeout +06-Jul-19 20:10:29: INFO - Timeout +06-Jul-19 20:10:39: INFO - Timeout +06-Jul-19 20:10:49: INFO - Timeout +06-Jul-19 20:10:59: INFO - Timeout +06-Jul-19 20:11:09: INFO - Timeout +06-Jul-19 20:11:19: INFO - Timeout +06-Jul-19 20:11:29: INFO - Timeout +06-Jul-19 20:11:39: INFO - Timeout +06-Jul-19 20:11:49: INFO - Timeout +06-Jul-19 20:11:59: INFO - Timeout +06-Jul-19 20:12:09: INFO - Timeout +06-Jul-19 20:12:19: INFO - Timeout +06-Jul-19 20:12:29: INFO - Timeout +06-Jul-19 20:12:39: INFO - Timeout +06-Jul-19 20:12:49: INFO - Timeout +06-Jul-19 20:12:59: INFO - Timeout +06-Jul-19 20:13:09: INFO - Timeout +06-Jul-19 20:13:19: INFO - Timeout +06-Jul-19 20:13:29: INFO - Timeout +06-Jul-19 20:13:39: INFO - Timeout +06-Jul-19 20:13:49: INFO - Timeout +06-Jul-19 20:13:59: INFO - Timeout +06-Jul-19 20:14:09: INFO - Timeout +06-Jul-19 20:14:19: INFO - Timeout +06-Jul-19 20:14:29: INFO - Timeout +06-Jul-19 20:14:39: INFO - Timeout +06-Jul-19 20:14:49: INFO - Timeout +06-Jul-19 20:14:59: INFO - Timeout +06-Jul-19 20:15:09: INFO - Timeout +06-Jul-19 20:15:19: INFO - Timeout +06-Jul-19 20:15:29: INFO - Timeout +06-Jul-19 20:15:39: INFO - Timeout +06-Jul-19 20:15:49: INFO - Timeout +06-Jul-19 20:15:59: INFO - Timeout +06-Jul-19 20:16:09: INFO - Timeout +06-Jul-19 20:16:19: INFO - Timeout +06-Jul-19 20:16:29: INFO - Timeout +06-Jul-19 20:16:39: INFO - Timeout +06-Jul-19 20:16:49: INFO - Timeout +06-Jul-19 20:16:59: INFO - Timeout +06-Jul-19 20:17:09: INFO - Timeout +06-Jul-19 20:17:19: INFO - Timeout +06-Jul-19 20:17:29: INFO - Timeout +06-Jul-19 20:17:39: INFO - Timeout +06-Jul-19 20:17:49: INFO - Timeout +06-Jul-19 20:17:59: INFO - Timeout +06-Jul-19 20:18:09: INFO - Timeout +06-Jul-19 20:18:19: INFO - Timeout +06-Jul-19 20:18:29: INFO - Timeout +06-Jul-19 20:18:39: INFO - Timeout +06-Jul-19 20:18:49: INFO - Timeout +06-Jul-19 20:18:59: INFO - Timeout +06-Jul-19 20:19:09: INFO - Timeout +06-Jul-19 20:19:19: INFO - Timeout +06-Jul-19 20:19:29: INFO - Timeout +06-Jul-19 20:19:39: INFO - Timeout +06-Jul-19 20:19:49: INFO - Timeout +06-Jul-19 20:19:59: INFO - Timeout +06-Jul-19 20:20:09: INFO - Timeout +06-Jul-19 20:20:19: INFO - Timeout +06-Jul-19 20:20:29: INFO - Timeout +06-Jul-19 20:20:39: INFO - Timeout +06-Jul-19 20:20:49: INFO - Timeout +06-Jul-19 20:20:59: INFO - Timeout +06-Jul-19 20:21:09: INFO - Timeout +06-Jul-19 20:21:19: INFO - Timeout +06-Jul-19 20:21:29: INFO - Timeout +06-Jul-19 20:21:39: INFO - Timeout +06-Jul-19 20:21:49: INFO - Timeout +06-Jul-19 20:21:59: INFO - Timeout +06-Jul-19 20:22:09: INFO - Timeout +06-Jul-19 20:22:19: INFO - Timeout +06-Jul-19 20:22:29: INFO - Timeout +06-Jul-19 20:22:39: INFO - Timeout +06-Jul-19 20:22:49: INFO - Timeout +06-Jul-19 20:22:59: INFO - Timeout +06-Jul-19 20:23:09: INFO - Timeout +06-Jul-19 20:23:19: INFO - Timeout +06-Jul-19 20:23:29: INFO - Timeout +06-Jul-19 20:23:39: INFO - Timeout +06-Jul-19 20:23:49: INFO - Timeout +06-Jul-19 20:23:59: INFO - Timeout +06-Jul-19 20:24:09: INFO - Timeout +06-Jul-19 20:24:19: INFO - Timeout +06-Jul-19 20:24:29: INFO - Timeout +06-Jul-19 20:24:39: INFO - Timeout +06-Jul-19 20:24:49: INFO - Timeout +06-Jul-19 20:24:59: INFO - Timeout +06-Jul-19 20:25:09: INFO - Timeout +06-Jul-19 20:25:19: INFO - Timeout +06-Jul-19 20:25:29: INFO - Timeout +06-Jul-19 20:25:39: INFO - Timeout +06-Jul-19 20:25:49: INFO - Timeout +06-Jul-19 20:25:59: INFO - Timeout +06-Jul-19 20:26:09: INFO - Timeout +06-Jul-19 20:26:19: INFO - Timeout +06-Jul-19 20:26:29: INFO - Timeout +06-Jul-19 20:26:39: INFO - Timeout +06-Jul-19 20:26:49: INFO - Timeout +06-Jul-19 20:26:59: INFO - Timeout +06-Jul-19 20:27:09: INFO - Timeout +06-Jul-19 20:27:19: INFO - Timeout +06-Jul-19 20:27:29: INFO - Timeout +06-Jul-19 20:27:39: INFO - Timeout +06-Jul-19 20:27:49: INFO - Timeout +06-Jul-19 20:27:59: INFO - Timeout +06-Jul-19 20:28:09: INFO - Timeout +06-Jul-19 20:28:19: INFO - Timeout +06-Jul-19 20:28:29: INFO - Timeout +06-Jul-19 20:28:39: INFO - Timeout +06-Jul-19 20:28:49: INFO - Timeout +06-Jul-19 20:28:59: INFO - Timeout +06-Jul-19 20:29:09: INFO - Timeout +06-Jul-19 20:29:19: INFO - Timeout +06-Jul-19 20:29:29: INFO - Timeout +06-Jul-19 20:29:39: INFO - Timeout +06-Jul-19 20:29:49: INFO - Timeout +06-Jul-19 20:29:59: INFO - Timeout +06-Jul-19 20:30:09: INFO - Timeout +06-Jul-19 20:30:19: INFO - Timeout +06-Jul-19 20:30:29: INFO - Timeout +06-Jul-19 20:30:39: INFO - Timeout +06-Jul-19 20:30:49: INFO - Timeout +06-Jul-19 20:30:59: INFO - Timeout +06-Jul-19 20:31:09: INFO - Timeout +06-Jul-19 20:31:19: INFO - Timeout +06-Jul-19 20:31:29: INFO - Timeout +06-Jul-19 20:31:39: INFO - Timeout +06-Jul-19 20:31:49: INFO - Timeout +06-Jul-19 20:31:59: INFO - Timeout +06-Jul-19 20:32:09: INFO - Timeout +06-Jul-19 20:32:19: INFO - Timeout +06-Jul-19 20:32:29: INFO - Timeout +06-Jul-19 20:32:39: INFO - Timeout +06-Jul-19 20:32:49: INFO - Timeout +06-Jul-19 20:32:59: INFO - Timeout +06-Jul-19 20:33:09: INFO - Timeout +06-Jul-19 20:33:19: INFO - Timeout +06-Jul-19 20:33:29: INFO - Timeout +06-Jul-19 20:33:39: INFO - Timeout +06-Jul-19 20:33:49: INFO - Timeout +06-Jul-19 20:33:59: INFO - Timeout +06-Jul-19 20:34:09: INFO - Timeout +06-Jul-19 20:34:19: INFO - Timeout +06-Jul-19 20:34:29: INFO - Timeout +06-Jul-19 20:34:39: INFO - Timeout +06-Jul-19 20:34:49: INFO - Timeout +06-Jul-19 20:34:59: INFO - Timeout +06-Jul-19 20:35:09: INFO - Timeout +06-Jul-19 20:35:19: INFO - Timeout +06-Jul-19 20:35:29: INFO - Timeout +06-Jul-19 20:35:39: INFO - Timeout +06-Jul-19 20:35:49: INFO - Timeout +06-Jul-19 20:35:59: INFO - Timeout +06-Jul-19 20:36:09: INFO - Timeout +06-Jul-19 20:36:19: INFO - Timeout +06-Jul-19 20:36:29: INFO - Timeout +06-Jul-19 20:36:39: INFO - Timeout +06-Jul-19 20:36:49: INFO - Timeout +06-Jul-19 20:36:59: INFO - Timeout +06-Jul-19 20:37:09: INFO - Timeout +06-Jul-19 20:37:19: INFO - Timeout +06-Jul-19 20:37:29: INFO - Timeout +06-Jul-19 20:37:39: INFO - Timeout +06-Jul-19 20:37:49: INFO - Timeout +06-Jul-19 20:37:59: INFO - Timeout +06-Jul-19 20:38:09: INFO - Timeout +06-Jul-19 20:38:19: INFO - Timeout +06-Jul-19 20:38:29: INFO - Timeout +06-Jul-19 20:38:39: INFO - Timeout +06-Jul-19 20:38:49: INFO - Timeout +06-Jul-19 20:38:59: INFO - Timeout +06-Jul-19 20:39:09: INFO - Timeout +06-Jul-19 20:39:19: INFO - Timeout +06-Jul-19 20:39:29: INFO - Timeout +06-Jul-19 20:39:39: INFO - Timeout +06-Jul-19 20:39:49: INFO - Timeout +06-Jul-19 20:39:59: INFO - Timeout +06-Jul-19 20:40:09: INFO - Timeout +06-Jul-19 20:40:19: INFO - Timeout +06-Jul-19 20:40:29: INFO - Timeout +06-Jul-19 20:40:39: INFO - Timeout +06-Jul-19 20:40:49: INFO - Timeout +06-Jul-19 20:40:59: INFO - Timeout +06-Jul-19 20:41:09: INFO - Timeout +06-Jul-19 20:41:19: INFO - Timeout +06-Jul-19 20:41:29: INFO - Timeout +06-Jul-19 20:41:39: INFO - Timeout +06-Jul-19 20:41:49: INFO - Timeout +06-Jul-19 20:41:59: INFO - Timeout +06-Jul-19 20:42:09: INFO - Timeout +06-Jul-19 20:42:19: INFO - Timeout +06-Jul-19 20:42:29: INFO - Timeout +06-Jul-19 20:42:39: INFO - Timeout +06-Jul-19 20:42:49: INFO - Timeout +06-Jul-19 20:42:59: INFO - Timeout +06-Jul-19 20:43:09: INFO - Timeout +06-Jul-19 20:43:19: INFO - Timeout +06-Jul-19 20:43:29: INFO - Timeout +06-Jul-19 20:43:39: INFO - Timeout +06-Jul-19 20:43:49: INFO - Timeout +06-Jul-19 20:43:59: INFO - Timeout +06-Jul-19 20:44:09: INFO - Timeout +06-Jul-19 20:44:19: INFO - Timeout +06-Jul-19 20:44:29: INFO - Timeout +06-Jul-19 20:44:39: INFO - Timeout +06-Jul-19 20:44:49: INFO - Timeout +06-Jul-19 20:44:59: INFO - Timeout +06-Jul-19 20:45:09: INFO - Timeout +06-Jul-19 20:45:19: INFO - Timeout +06-Jul-19 20:45:29: INFO - Timeout +06-Jul-19 20:45:39: INFO - Timeout +06-Jul-19 20:45:49: INFO - Timeout +06-Jul-19 20:45:59: INFO - Timeout +06-Jul-19 20:46:09: INFO - Timeout +06-Jul-19 20:46:19: INFO - Timeout +06-Jul-19 20:46:29: INFO - Timeout +06-Jul-19 20:46:39: INFO - Timeout +06-Jul-19 20:46:49: INFO - Timeout +06-Jul-19 20:46:59: INFO - Timeout +06-Jul-19 20:47:09: INFO - Timeout +06-Jul-19 20:47:19: INFO - Timeout +06-Jul-19 20:47:29: INFO - Timeout +06-Jul-19 20:47:39: INFO - Timeout +06-Jul-19 20:47:49: INFO - Timeout +06-Jul-19 20:47:59: INFO - Timeout +06-Jul-19 20:48:09: INFO - Timeout +06-Jul-19 20:48:19: INFO - Timeout +06-Jul-19 20:48:29: INFO - Timeout +06-Jul-19 20:48:39: INFO - Timeout +06-Jul-19 20:48:49: INFO - Timeout +06-Jul-19 20:48:59: INFO - Timeout +06-Jul-19 20:49:09: INFO - Timeout +06-Jul-19 20:49:19: INFO - Timeout +06-Jul-19 20:49:29: INFO - Timeout +06-Jul-19 20:49:39: INFO - Timeout +06-Jul-19 20:49:49: INFO - Timeout +06-Jul-19 20:49:59: INFO - Timeout +06-Jul-19 20:50:09: INFO - Timeout +06-Jul-19 20:50:19: INFO - Timeout +06-Jul-19 20:50:29: INFO - Timeout +06-Jul-19 20:50:39: INFO - Timeout +06-Jul-19 20:50:49: INFO - Timeout +06-Jul-19 20:50:59: INFO - Timeout +06-Jul-19 20:51:09: INFO - Timeout +06-Jul-19 20:51:19: INFO - Timeout +06-Jul-19 20:51:29: INFO - Timeout +06-Jul-19 20:51:39: INFO - Timeout +06-Jul-19 20:51:49: INFO - Timeout +06-Jul-19 20:51:59: INFO - Timeout +06-Jul-19 20:52:09: INFO - Timeout +06-Jul-19 20:52:19: INFO - Timeout +06-Jul-19 20:52:29: INFO - Timeout +06-Jul-19 20:52:39: INFO - Timeout +06-Jul-19 20:52:49: INFO - Timeout +06-Jul-19 20:52:59: INFO - Timeout +06-Jul-19 20:53:09: INFO - Timeout +06-Jul-19 20:53:19: INFO - Timeout +06-Jul-19 20:53:29: INFO - Timeout +06-Jul-19 20:53:39: INFO - Timeout +06-Jul-19 20:53:49: INFO - Timeout +06-Jul-19 20:53:59: INFO - Timeout +06-Jul-19 20:54:09: INFO - Timeout +06-Jul-19 20:54:19: INFO - Timeout +06-Jul-19 20:54:29: INFO - Timeout +06-Jul-19 20:54:39: INFO - Timeout +06-Jul-19 20:54:49: INFO - Timeout +06-Jul-19 20:54:59: INFO - Timeout +06-Jul-19 20:55:09: INFO - Timeout +06-Jul-19 20:55:19: INFO - Timeout +06-Jul-19 20:55:29: INFO - Timeout +06-Jul-19 20:55:39: INFO - Timeout +06-Jul-19 20:55:49: INFO - Timeout +06-Jul-19 20:55:59: INFO - Timeout +06-Jul-19 20:56:09: INFO - Timeout +06-Jul-19 20:56:19: INFO - Timeout +06-Jul-19 20:56:29: INFO - Timeout +06-Jul-19 20:56:39: INFO - Timeout +06-Jul-19 20:56:49: INFO - Timeout +06-Jul-19 20:56:59: INFO - Timeout +06-Jul-19 20:57:09: INFO - Timeout +06-Jul-19 20:57:19: INFO - Timeout +06-Jul-19 20:57:29: INFO - Timeout +06-Jul-19 20:57:39: INFO - Timeout +06-Jul-19 20:57:49: INFO - Timeout +06-Jul-19 20:57:59: INFO - Timeout +06-Jul-19 20:58:09: INFO - Timeout +06-Jul-19 20:58:19: INFO - Timeout +06-Jul-19 20:58:29: INFO - Timeout +06-Jul-19 20:58:39: INFO - Timeout +06-Jul-19 20:58:49: INFO - Timeout +06-Jul-19 20:58:59: INFO - Timeout +06-Jul-19 20:59:09: INFO - Timeout +06-Jul-19 20:59:19: INFO - Timeout +06-Jul-19 20:59:29: INFO - Timeout +06-Jul-19 20:59:39: INFO - Timeout +06-Jul-19 20:59:49: INFO - Timeout +06-Jul-19 20:59:59: INFO - Timeout +06-Jul-19 21:00:09: INFO - Timeout +06-Jul-19 21:00:19: INFO - Timeout +06-Jul-19 21:00:29: INFO - Timeout +06-Jul-19 21:00:39: INFO - Timeout +06-Jul-19 21:00:49: INFO - Timeout +06-Jul-19 21:00:59: INFO - Timeout +06-Jul-19 21:01:09: INFO - Timeout +06-Jul-19 21:01:19: INFO - Timeout +06-Jul-19 21:01:29: INFO - Timeout +06-Jul-19 21:01:39: INFO - Timeout +06-Jul-19 21:01:49: INFO - Timeout +06-Jul-19 21:01:59: INFO - Timeout +06-Jul-19 21:02:09: INFO - Timeout +06-Jul-19 21:02:19: INFO - Timeout +06-Jul-19 21:02:29: INFO - Timeout +06-Jul-19 21:02:39: INFO - Timeout +06-Jul-19 21:02:49: INFO - Timeout +06-Jul-19 21:02:59: INFO - Timeout +06-Jul-19 21:03:09: INFO - Timeout +06-Jul-19 21:03:19: INFO - Timeout +06-Jul-19 21:03:29: INFO - Timeout +06-Jul-19 21:03:39: INFO - Timeout +06-Jul-19 21:03:49: INFO - Timeout +06-Jul-19 21:03:59: INFO - Timeout +06-Jul-19 21:04:09: INFO - Timeout +06-Jul-19 21:04:19: INFO - Timeout +06-Jul-19 21:04:29: INFO - Timeout +06-Jul-19 21:04:39: INFO - Timeout +06-Jul-19 21:04:49: INFO - Timeout +06-Jul-19 21:04:59: INFO - Timeout +06-Jul-19 21:05:09: INFO - Timeout +06-Jul-19 21:05:19: INFO - Timeout +06-Jul-19 21:05:29: INFO - Timeout +06-Jul-19 21:05:39: INFO - Timeout +06-Jul-19 21:05:49: INFO - Timeout +06-Jul-19 21:05:59: INFO - Timeout +06-Jul-19 21:06:09: INFO - Timeout +06-Jul-19 21:06:19: INFO - Timeout +06-Jul-19 21:06:29: INFO - Timeout +06-Jul-19 21:06:39: INFO - Timeout +06-Jul-19 21:06:49: INFO - Timeout +06-Jul-19 21:06:59: INFO - Timeout +06-Jul-19 21:07:09: INFO - Timeout +06-Jul-19 21:07:19: INFO - Timeout +06-Jul-19 21:07:29: INFO - Timeout +06-Jul-19 21:07:39: INFO - Timeout +06-Jul-19 21:07:49: INFO - Timeout +06-Jul-19 21:07:59: INFO - Timeout +06-Jul-19 21:08:09: INFO - Timeout +06-Jul-19 21:08:19: INFO - Timeout +06-Jul-19 21:08:29: INFO - Timeout +06-Jul-19 21:08:39: INFO - Timeout +06-Jul-19 21:08:49: INFO - Timeout +06-Jul-19 21:08:59: INFO - Timeout +06-Jul-19 21:09:09: INFO - Timeout +06-Jul-19 21:09:19: INFO - Timeout +06-Jul-19 21:09:29: INFO - Timeout +06-Jul-19 21:09:39: INFO - Timeout +06-Jul-19 21:09:49: INFO - Timeout +06-Jul-19 21:09:59: INFO - Timeout +06-Jul-19 21:10:09: INFO - Timeout +06-Jul-19 21:10:19: INFO - Timeout +06-Jul-19 21:10:29: INFO - Timeout +06-Jul-19 21:10:39: INFO - Timeout +06-Jul-19 21:10:49: INFO - Timeout +06-Jul-19 21:10:59: INFO - Timeout +06-Jul-19 21:11:09: INFO - Timeout +06-Jul-19 21:11:19: INFO - Timeout +06-Jul-19 21:11:29: INFO - Timeout +06-Jul-19 21:11:39: INFO - Timeout +06-Jul-19 21:11:49: INFO - Timeout +06-Jul-19 21:11:59: INFO - Timeout +06-Jul-19 21:12:09: INFO - Timeout +06-Jul-19 21:12:19: INFO - Timeout +06-Jul-19 21:12:29: INFO - Timeout +06-Jul-19 21:12:39: INFO - Timeout +06-Jul-19 21:12:50: INFO - Timeout +06-Jul-19 21:13:00: INFO - Timeout +06-Jul-19 21:13:10: INFO - Timeout +06-Jul-19 21:13:20: INFO - Timeout +06-Jul-19 21:13:30: INFO - Timeout +06-Jul-19 21:13:40: INFO - Timeout +06-Jul-19 21:13:50: INFO - Timeout +06-Jul-19 21:14:00: INFO - Timeout +06-Jul-19 21:14:10: INFO - Timeout +06-Jul-19 21:14:20: INFO - Timeout +06-Jul-19 21:14:30: INFO - Timeout +06-Jul-19 21:14:40: INFO - Timeout +06-Jul-19 21:14:50: INFO - Timeout +06-Jul-19 21:15:00: INFO - Timeout +06-Jul-19 21:15:10: INFO - Timeout +06-Jul-19 21:15:20: INFO - Timeout +06-Jul-19 21:15:30: INFO - Timeout +06-Jul-19 21:15:40: INFO - Timeout +06-Jul-19 21:15:50: INFO - Timeout +06-Jul-19 21:16:00: INFO - Timeout +06-Jul-19 21:16:10: INFO - Timeout +06-Jul-19 21:16:20: INFO - Timeout +06-Jul-19 21:16:30: INFO - Timeout +06-Jul-19 21:16:40: INFO - Timeout +06-Jul-19 21:16:50: INFO - Timeout +06-Jul-19 21:17:00: INFO - Timeout +06-Jul-19 21:17:10: INFO - Timeout +06-Jul-19 21:17:20: INFO - Timeout +06-Jul-19 21:17:30: INFO - Timeout +06-Jul-19 21:17:40: INFO - Timeout +06-Jul-19 21:17:50: INFO - Timeout +06-Jul-19 21:18:00: INFO - Timeout +06-Jul-19 21:18:10: INFO - Timeout +06-Jul-19 21:18:20: INFO - Timeout +06-Jul-19 21:18:30: INFO - Timeout +06-Jul-19 21:18:40: INFO - Timeout +06-Jul-19 21:18:50: INFO - Timeout +06-Jul-19 21:19:00: INFO - Timeout +06-Jul-19 21:19:10: INFO - Timeout +06-Jul-19 21:19:20: INFO - Timeout +06-Jul-19 21:19:30: INFO - Timeout +06-Jul-19 21:19:40: INFO - Timeout +06-Jul-19 21:19:50: INFO - Timeout +06-Jul-19 21:20:00: INFO - Timeout +06-Jul-19 21:20:10: INFO - Timeout +06-Jul-19 21:20:20: INFO - Timeout +06-Jul-19 21:20:30: INFO - Timeout +06-Jul-19 21:20:40: INFO - Timeout +06-Jul-19 21:20:50: INFO - Timeout +06-Jul-19 21:21:00: INFO - Timeout +06-Jul-19 21:21:10: INFO - Timeout +06-Jul-19 21:21:20: INFO - Timeout +06-Jul-19 21:21:30: INFO - Timeout +06-Jul-19 21:21:40: INFO - Timeout +06-Jul-19 21:21:50: INFO - Timeout +06-Jul-19 21:22:00: INFO - Timeout +06-Jul-19 21:22:10: INFO - Timeout +06-Jul-19 21:22:20: INFO - Timeout +06-Jul-19 21:22:30: INFO - Timeout +06-Jul-19 21:22:40: INFO - Timeout +06-Jul-19 21:22:50: INFO - Timeout +06-Jul-19 21:23:00: INFO - Timeout +06-Jul-19 21:23:10: INFO - Timeout +06-Jul-19 21:23:20: INFO - Timeout +06-Jul-19 21:23:30: INFO - Timeout +06-Jul-19 21:23:40: INFO - Timeout +06-Jul-19 21:23:50: INFO - Timeout +06-Jul-19 21:24:00: INFO - Timeout +06-Jul-19 21:24:10: INFO - Timeout +06-Jul-19 21:24:20: INFO - Timeout +06-Jul-19 21:24:30: INFO - Timeout +06-Jul-19 21:24:40: INFO - Timeout +06-Jul-19 21:24:50: INFO - Timeout +06-Jul-19 21:25:00: INFO - Timeout +06-Jul-19 21:25:10: INFO - Timeout +06-Jul-19 21:25:20: INFO - Timeout +06-Jul-19 21:25:30: INFO - Timeout +06-Jul-19 21:25:40: INFO - Timeout +06-Jul-19 21:25:50: INFO - Timeout +06-Jul-19 21:26:00: INFO - Timeout +06-Jul-19 21:26:10: INFO - Timeout +06-Jul-19 21:26:20: INFO - Timeout +06-Jul-19 21:26:30: INFO - Timeout +06-Jul-19 21:26:40: INFO - Timeout +06-Jul-19 21:26:50: INFO - Timeout +06-Jul-19 21:27:00: INFO - Timeout +06-Jul-19 21:27:10: INFO - Timeout +06-Jul-19 21:27:20: INFO - Timeout +06-Jul-19 21:27:30: INFO - Timeout +06-Jul-19 21:27:40: INFO - Timeout +06-Jul-19 21:27:50: INFO - Timeout +06-Jul-19 21:28:00: INFO - Timeout +06-Jul-19 21:28:10: INFO - Timeout +06-Jul-19 21:28:20: INFO - Timeout +06-Jul-19 21:28:30: INFO - Timeout +06-Jul-19 21:28:40: INFO - Timeout +06-Jul-19 21:28:50: INFO - Timeout +06-Jul-19 21:29:00: INFO - Timeout +06-Jul-19 21:29:10: INFO - Timeout +06-Jul-19 21:29:20: INFO - Timeout +06-Jul-19 21:29:30: INFO - Timeout +06-Jul-19 21:29:40: INFO - Timeout +06-Jul-19 21:29:50: INFO - Timeout +06-Jul-19 21:30:00: INFO - Timeout +06-Jul-19 21:30:10: INFO - Timeout +06-Jul-19 21:30:20: INFO - Timeout +06-Jul-19 21:30:30: INFO - Timeout +06-Jul-19 21:30:40: INFO - Timeout +06-Jul-19 21:30:50: INFO - Timeout +06-Jul-19 21:31:00: INFO - Timeout +06-Jul-19 21:31:10: INFO - Timeout +06-Jul-19 21:31:20: INFO - Timeout +06-Jul-19 21:31:30: INFO - Timeout +06-Jul-19 21:31:40: INFO - Timeout +06-Jul-19 21:31:50: INFO - Timeout +06-Jul-19 21:32:00: INFO - Timeout +06-Jul-19 21:32:10: INFO - Timeout +06-Jul-19 21:32:20: INFO - Timeout +06-Jul-19 21:32:30: INFO - Timeout +06-Jul-19 21:32:40: INFO - Timeout +06-Jul-19 21:32:50: INFO - Timeout +06-Jul-19 21:33:00: INFO - Timeout +06-Jul-19 21:33:10: INFO - Timeout +06-Jul-19 21:33:20: INFO - Timeout +06-Jul-19 21:33:30: INFO - Timeout +06-Jul-19 21:33:40: INFO - Timeout +06-Jul-19 21:33:50: INFO - Timeout +06-Jul-19 21:34:00: INFO - Timeout +06-Jul-19 21:34:10: INFO - Timeout +06-Jul-19 21:34:20: INFO - Timeout +06-Jul-19 21:34:30: INFO - Timeout +06-Jul-19 21:34:40: INFO - Timeout +06-Jul-19 21:34:50: INFO - Timeout +06-Jul-19 21:35:00: INFO - Timeout +06-Jul-19 21:35:10: INFO - Timeout +06-Jul-19 21:35:20: INFO - Timeout +06-Jul-19 21:35:30: INFO - Timeout +06-Jul-19 21:35:40: INFO - Timeout +06-Jul-19 21:35:50: INFO - Timeout +06-Jul-19 21:36:00: INFO - Timeout +06-Jul-19 21:36:10: INFO - Timeout +06-Jul-19 21:36:20: INFO - Timeout +06-Jul-19 21:36:30: INFO - Timeout +06-Jul-19 21:36:40: INFO - Timeout +06-Jul-19 21:36:50: INFO - Timeout +06-Jul-19 21:37:00: INFO - Timeout +06-Jul-19 21:37:10: INFO - Timeout +06-Jul-19 21:37:20: INFO - Timeout +06-Jul-19 21:37:30: INFO - Timeout +06-Jul-19 21:37:40: INFO - Timeout +06-Jul-19 21:37:50: INFO - Timeout +06-Jul-19 21:38:00: INFO - Timeout +06-Jul-19 21:38:10: INFO - Timeout +06-Jul-19 21:38:20: INFO - Timeout +06-Jul-19 21:38:30: INFO - Timeout +06-Jul-19 21:38:40: INFO - Timeout +06-Jul-19 21:38:50: INFO - Timeout +06-Jul-19 21:39:00: INFO - Timeout +06-Jul-19 21:39:10: INFO - Timeout +06-Jul-19 21:39:20: INFO - Timeout +06-Jul-19 21:39:30: INFO - Timeout +06-Jul-19 21:39:40: INFO - Timeout +06-Jul-19 21:39:50: INFO - Timeout +06-Jul-19 21:40:00: INFO - Timeout +06-Jul-19 21:40:10: INFO - Timeout +06-Jul-19 21:40:20: INFO - Timeout +06-Jul-19 21:40:30: INFO - Timeout +06-Jul-19 21:40:40: INFO - Timeout +06-Jul-19 21:40:50: INFO - Timeout +06-Jul-19 21:41:00: INFO - Timeout +06-Jul-19 21:41:10: INFO - Timeout +06-Jul-19 21:41:20: INFO - Timeout +06-Jul-19 21:41:30: INFO - Timeout +06-Jul-19 21:41:40: INFO - Timeout +06-Jul-19 21:41:50: INFO - Timeout +06-Jul-19 21:42:00: INFO - Timeout +06-Jul-19 21:42:10: INFO - Timeout +06-Jul-19 21:42:20: INFO - Timeout +06-Jul-19 21:42:30: INFO - Timeout +06-Jul-19 21:42:40: INFO - Timeout +06-Jul-19 21:42:50: INFO - Timeout +06-Jul-19 21:43:00: INFO - Timeout +06-Jul-19 21:43:10: INFO - Timeout +06-Jul-19 21:43:20: INFO - Timeout +06-Jul-19 21:43:30: INFO - Timeout +06-Jul-19 21:43:40: INFO - Timeout +06-Jul-19 21:43:50: INFO - Timeout +06-Jul-19 21:44:00: INFO - Timeout +06-Jul-19 21:44:10: INFO - Timeout +06-Jul-19 21:44:20: INFO - Timeout +06-Jul-19 21:44:30: INFO - Timeout +06-Jul-19 21:44:40: INFO - Timeout +06-Jul-19 21:44:50: INFO - Timeout +06-Jul-19 21:45:00: INFO - Timeout +06-Jul-19 21:45:10: INFO - Timeout +06-Jul-19 21:45:20: INFO - Timeout +06-Jul-19 21:45:30: INFO - Timeout +06-Jul-19 21:45:40: INFO - Timeout +06-Jul-19 21:45:50: INFO - Timeout +06-Jul-19 21:46:00: INFO - Timeout +06-Jul-19 21:46:10: INFO - Timeout +06-Jul-19 21:46:20: INFO - Timeout +06-Jul-19 21:46:30: INFO - Timeout +06-Jul-19 21:46:40: INFO - Timeout +06-Jul-19 21:46:50: INFO - Timeout +06-Jul-19 21:47:00: INFO - Timeout +06-Jul-19 21:47:10: INFO - Timeout +06-Jul-19 21:47:20: INFO - Timeout +06-Jul-19 21:47:30: INFO - Timeout +06-Jul-19 21:47:40: INFO - Timeout +06-Jul-19 21:47:50: INFO - Timeout +06-Jul-19 21:48:00: INFO - Timeout +06-Jul-19 21:48:10: INFO - Timeout +06-Jul-19 21:48:20: INFO - Timeout +06-Jul-19 21:48:30: INFO - Timeout +06-Jul-19 21:48:40: INFO - Timeout +06-Jul-19 21:48:50: INFO - Timeout +06-Jul-19 21:49:00: INFO - Timeout +06-Jul-19 21:49:10: INFO - Timeout +06-Jul-19 21:49:20: INFO - Timeout +06-Jul-19 21:49:30: INFO - Timeout +06-Jul-19 21:49:40: INFO - Timeout +06-Jul-19 21:49:50: INFO - Timeout +06-Jul-19 21:50:00: INFO - Timeout +06-Jul-19 21:50:10: INFO - Timeout +06-Jul-19 21:50:20: INFO - Timeout +06-Jul-19 21:50:30: INFO - Timeout +06-Jul-19 21:50:40: INFO - Timeout +06-Jul-19 21:50:50: INFO - Timeout +06-Jul-19 21:51:00: INFO - Timeout +06-Jul-19 21:51:10: INFO - Timeout +06-Jul-19 21:51:20: INFO - Timeout +06-Jul-19 21:51:30: INFO - Timeout +06-Jul-19 21:51:40: INFO - Timeout +06-Jul-19 21:51:50: INFO - Timeout +06-Jul-19 21:52:00: INFO - Timeout +06-Jul-19 21:52:10: INFO - Timeout +06-Jul-19 21:52:20: INFO - Timeout +06-Jul-19 21:52:30: INFO - Timeout +06-Jul-19 21:52:40: INFO - Timeout +06-Jul-19 21:52:50: INFO - Timeout +06-Jul-19 21:53:00: INFO - Timeout +06-Jul-19 21:53:10: INFO - Timeout +06-Jul-19 21:53:20: INFO - Timeout +06-Jul-19 21:53:30: INFO - Timeout +06-Jul-19 21:53:40: INFO - Timeout +06-Jul-19 21:53:50: INFO - Timeout +06-Jul-19 21:54:00: INFO - Timeout +06-Jul-19 21:54:10: INFO - Timeout +06-Jul-19 21:54:20: INFO - Timeout +06-Jul-19 21:54:30: INFO - Timeout +06-Jul-19 21:54:40: INFO - Timeout +06-Jul-19 21:54:50: INFO - Timeout +06-Jul-19 21:55:00: INFO - Timeout +06-Jul-19 21:55:10: INFO - Timeout +06-Jul-19 21:55:20: INFO - Timeout +06-Jul-19 21:55:30: INFO - Timeout +06-Jul-19 21:55:40: INFO - Timeout +06-Jul-19 21:55:50: INFO - Timeout +06-Jul-19 21:56:00: INFO - Timeout +06-Jul-19 21:56:10: INFO - Timeout +06-Jul-19 21:56:20: INFO - Timeout +06-Jul-19 21:56:30: INFO - Timeout +06-Jul-19 21:56:40: INFO - Timeout +06-Jul-19 21:56:50: INFO - Timeout +06-Jul-19 21:57:00: INFO - Timeout +06-Jul-19 21:57:10: INFO - Timeout +06-Jul-19 21:57:20: INFO - Timeout +06-Jul-19 21:57:30: INFO - Timeout +06-Jul-19 21:57:40: INFO - Timeout +06-Jul-19 21:57:50: INFO - Timeout +06-Jul-19 21:58:00: INFO - Timeout +06-Jul-19 21:58:10: INFO - Timeout +06-Jul-19 21:58:20: INFO - Timeout +06-Jul-19 21:58:30: INFO - Timeout +06-Jul-19 21:58:40: INFO - Timeout +06-Jul-19 21:58:50: INFO - Timeout +06-Jul-19 21:59:00: INFO - Timeout +06-Jul-19 21:59:10: INFO - Timeout +06-Jul-19 21:59:20: INFO - Timeout +06-Jul-19 21:59:30: INFO - Timeout +06-Jul-19 21:59:40: INFO - Timeout +06-Jul-19 21:59:50: INFO - Timeout +06-Jul-19 22:00:00: INFO - Timeout +06-Jul-19 22:00:10: INFO - Timeout +06-Jul-19 22:00:20: INFO - Timeout +06-Jul-19 22:00:30: INFO - Timeout +06-Jul-19 22:00:40: INFO - Timeout +06-Jul-19 22:00:50: INFO - Timeout +06-Jul-19 22:01:00: INFO - Timeout +06-Jul-19 22:01:10: INFO - Timeout +06-Jul-19 22:01:20: INFO - Timeout +06-Jul-19 22:01:30: INFO - Timeout +06-Jul-19 22:01:40: INFO - Timeout +06-Jul-19 22:01:50: INFO - Timeout +06-Jul-19 22:02:00: INFO - Timeout +06-Jul-19 22:02:10: INFO - Timeout +06-Jul-19 22:02:20: INFO - Timeout +06-Jul-19 22:02:30: INFO - Timeout +06-Jul-19 22:02:40: INFO - Timeout +06-Jul-19 22:02:50: INFO - Timeout +06-Jul-19 22:03:00: INFO - Timeout +06-Jul-19 22:03:10: INFO - Timeout +06-Jul-19 22:03:20: INFO - Timeout +06-Jul-19 22:03:30: INFO - Timeout +06-Jul-19 22:03:40: INFO - Timeout +06-Jul-19 22:03:50: INFO - Timeout +06-Jul-19 22:04:00: INFO - Timeout +06-Jul-19 22:04:10: INFO - Timeout +06-Jul-19 22:04:20: INFO - Timeout +06-Jul-19 22:04:30: INFO - Timeout +06-Jul-19 22:04:40: INFO - Timeout +06-Jul-19 22:04:50: INFO - Timeout +06-Jul-19 22:05:00: INFO - Timeout +06-Jul-19 22:05:10: INFO - Timeout +06-Jul-19 22:05:20: INFO - Timeout +06-Jul-19 22:05:30: INFO - Timeout +06-Jul-19 22:05:40: INFO - Timeout +06-Jul-19 22:05:50: INFO - Timeout +06-Jul-19 22:06:00: INFO - Timeout +06-Jul-19 22:06:10: INFO - Timeout +06-Jul-19 22:06:20: INFO - Timeout +06-Jul-19 22:06:30: INFO - Timeout +06-Jul-19 22:06:40: INFO - Timeout +06-Jul-19 22:06:50: INFO - Timeout +06-Jul-19 22:07:00: INFO - Timeout +06-Jul-19 22:07:10: INFO - Timeout +06-Jul-19 22:07:20: INFO - Timeout +06-Jul-19 22:07:30: INFO - Timeout +06-Jul-19 22:07:40: INFO - Timeout +06-Jul-19 22:07:50: INFO - Timeout +06-Jul-19 22:08:00: INFO - Timeout +06-Jul-19 22:08:10: INFO - Timeout +06-Jul-19 22:08:20: INFO - Timeout +06-Jul-19 22:08:30: INFO - Timeout +06-Jul-19 22:08:40: INFO - Timeout +06-Jul-19 22:08:50: INFO - Timeout +06-Jul-19 22:09:00: INFO - Timeout +06-Jul-19 22:09:10: INFO - Timeout +06-Jul-19 22:09:20: INFO - Timeout +06-Jul-19 22:09:30: INFO - Timeout +06-Jul-19 22:09:40: INFO - Timeout +06-Jul-19 22:09:50: INFO - Timeout +06-Jul-19 22:10:00: INFO - Timeout +06-Jul-19 22:10:10: INFO - Timeout +06-Jul-19 22:10:20: INFO - Timeout +06-Jul-19 22:10:30: INFO - Timeout +06-Jul-19 22:10:40: INFO - Timeout +06-Jul-19 22:10:50: INFO - Timeout +06-Jul-19 22:11:00: INFO - Timeout +06-Jul-19 22:11:10: INFO - Timeout +06-Jul-19 22:11:20: INFO - Timeout +06-Jul-19 22:11:30: INFO - Timeout +06-Jul-19 22:11:40: INFO - Timeout +06-Jul-19 22:11:50: INFO - Timeout +06-Jul-19 22:12:00: INFO - Timeout +06-Jul-19 22:12:10: INFO - Timeout +06-Jul-19 22:12:20: INFO - Timeout +06-Jul-19 22:12:30: INFO - Timeout +06-Jul-19 22:12:40: INFO - Timeout +06-Jul-19 22:12:50: INFO - Timeout +06-Jul-19 22:13:00: INFO - Timeout +06-Jul-19 22:13:10: INFO - Timeout +06-Jul-19 22:13:20: INFO - Timeout +06-Jul-19 22:13:30: INFO - Timeout +06-Jul-19 22:13:40: INFO - Timeout +06-Jul-19 22:13:50: INFO - Timeout +06-Jul-19 22:14:00: INFO - Timeout +06-Jul-19 22:14:10: INFO - Timeout +06-Jul-19 22:14:20: INFO - Timeout +06-Jul-19 22:14:30: INFO - Timeout +06-Jul-19 22:14:40: INFO - Timeout +06-Jul-19 22:14:50: INFO - Timeout +06-Jul-19 22:15:00: INFO - Timeout +06-Jul-19 22:15:10: INFO - Timeout +06-Jul-19 22:15:20: INFO - Timeout +06-Jul-19 22:15:30: INFO - Timeout +06-Jul-19 22:15:40: INFO - Timeout +06-Jul-19 22:15:50: INFO - Timeout +06-Jul-19 22:16:00: INFO - Timeout +06-Jul-19 22:16:10: INFO - Timeout +06-Jul-19 22:16:20: INFO - Timeout +06-Jul-19 22:16:30: INFO - Timeout +06-Jul-19 22:16:40: INFO - Timeout +06-Jul-19 22:16:50: INFO - Timeout +06-Jul-19 22:17:00: INFO - Timeout +06-Jul-19 22:17:10: INFO - Timeout +06-Jul-19 22:17:20: INFO - Timeout +06-Jul-19 22:17:30: INFO - Timeout +06-Jul-19 22:17:40: INFO - Timeout +06-Jul-19 22:17:50: INFO - Timeout +06-Jul-19 22:18:00: INFO - Timeout +06-Jul-19 22:18:10: INFO - Timeout +06-Jul-19 22:18:20: INFO - Timeout +06-Jul-19 22:18:30: INFO - Timeout +06-Jul-19 22:18:40: INFO - Timeout +06-Jul-19 22:18:50: INFO - Timeout +06-Jul-19 22:19:00: INFO - Timeout +06-Jul-19 22:19:10: INFO - Timeout +06-Jul-19 22:19:20: INFO - Timeout +06-Jul-19 22:19:30: INFO - Timeout +06-Jul-19 22:19:40: INFO - Timeout +06-Jul-19 22:19:50: INFO - Timeout +06-Jul-19 22:20:00: INFO - Timeout +06-Jul-19 22:20:10: INFO - Timeout +06-Jul-19 22:20:20: INFO - Timeout +06-Jul-19 22:20:30: INFO - Timeout +06-Jul-19 22:20:40: INFO - Timeout +06-Jul-19 22:20:50: INFO - Timeout +06-Jul-19 22:21:00: INFO - Timeout +06-Jul-19 22:21:10: INFO - Timeout +06-Jul-19 22:21:20: INFO - Timeout +06-Jul-19 22:21:30: INFO - Timeout +06-Jul-19 22:21:40: INFO - Timeout +06-Jul-19 22:21:50: INFO - Timeout +06-Jul-19 22:22:00: INFO - Timeout +06-Jul-19 22:22:10: INFO - Timeout +06-Jul-19 22:22:20: INFO - Timeout +06-Jul-19 22:22:30: INFO - Timeout +06-Jul-19 22:22:40: INFO - Timeout +06-Jul-19 22:22:50: INFO - Timeout +06-Jul-19 22:23:00: INFO - Timeout +06-Jul-19 22:23:10: INFO - Timeout +06-Jul-19 22:23:20: INFO - Timeout +06-Jul-19 22:23:30: INFO - Timeout +06-Jul-19 22:23:40: INFO - Timeout +06-Jul-19 22:23:50: INFO - Timeout +06-Jul-19 22:24:00: INFO - Timeout +06-Jul-19 22:24:10: INFO - Timeout +06-Jul-19 22:24:20: INFO - Timeout +06-Jul-19 22:24:30: INFO - Timeout +06-Jul-19 22:24:40: INFO - Timeout +06-Jul-19 22:24:50: INFO - Timeout +06-Jul-19 22:25:00: INFO - Timeout +06-Jul-19 22:25:10: INFO - Timeout +06-Jul-19 22:25:20: INFO - Timeout +06-Jul-19 22:25:30: INFO - Timeout +06-Jul-19 22:25:40: INFO - Timeout +06-Jul-19 22:25:50: INFO - Timeout +06-Jul-19 22:26:00: INFO - Timeout +06-Jul-19 22:26:10: INFO - Timeout +06-Jul-19 22:26:20: INFO - Timeout +06-Jul-19 22:26:30: INFO - Timeout +06-Jul-19 22:26:40: INFO - Timeout +06-Jul-19 22:26:50: INFO - Timeout +06-Jul-19 22:27:00: INFO - Timeout +06-Jul-19 22:27:10: INFO - Timeout +06-Jul-19 22:27:20: INFO - Timeout +06-Jul-19 22:27:30: INFO - Timeout +06-Jul-19 22:27:40: INFO - Timeout +06-Jul-19 22:27:50: INFO - Timeout +06-Jul-19 22:28:00: INFO - Timeout +06-Jul-19 22:28:10: INFO - Timeout +06-Jul-19 22:28:20: INFO - Timeout +06-Jul-19 22:28:30: INFO - Timeout +06-Jul-19 22:28:40: INFO - Timeout +06-Jul-19 22:28:50: INFO - Timeout +06-Jul-19 22:29:00: INFO - Timeout +06-Jul-19 22:29:10: INFO - Timeout +06-Jul-19 22:29:20: INFO - Timeout +06-Jul-19 22:29:30: INFO - Timeout +06-Jul-19 22:29:41: INFO - Timeout +06-Jul-19 22:29:51: INFO - Timeout +06-Jul-19 22:30:01: INFO - Timeout +06-Jul-19 22:30:11: INFO - Timeout +06-Jul-19 22:30:21: INFO - Timeout +06-Jul-19 22:30:31: INFO - Timeout +06-Jul-19 22:30:41: INFO - Timeout +06-Jul-19 22:30:51: INFO - Timeout +06-Jul-19 22:31:01: INFO - Timeout +06-Jul-19 22:31:11: INFO - Timeout +06-Jul-19 22:31:21: INFO - Timeout +06-Jul-19 22:31:31: INFO - Timeout +06-Jul-19 22:31:41: INFO - Timeout +06-Jul-19 22:31:51: INFO - Timeout +06-Jul-19 22:32:01: INFO - Timeout +06-Jul-19 22:32:11: INFO - Timeout +06-Jul-19 22:32:21: INFO - Timeout +06-Jul-19 22:32:31: INFO - Timeout +06-Jul-19 22:32:41: INFO - Timeout +06-Jul-19 22:32:51: INFO - Timeout +06-Jul-19 22:33:01: INFO - Timeout +06-Jul-19 22:33:11: INFO - Timeout +06-Jul-19 22:33:21: INFO - Timeout +06-Jul-19 22:33:31: INFO - Timeout +06-Jul-19 22:33:41: INFO - Timeout +06-Jul-19 22:33:51: INFO - Timeout +06-Jul-19 22:34:01: INFO - Timeout +06-Jul-19 22:34:11: INFO - Timeout +06-Jul-19 22:34:21: INFO - Timeout +06-Jul-19 22:34:31: INFO - Timeout +06-Jul-19 22:34:41: INFO - Timeout +06-Jul-19 22:34:51: INFO - Timeout +06-Jul-19 22:35:01: INFO - Timeout +06-Jul-19 22:35:11: INFO - Timeout +06-Jul-19 22:35:21: INFO - Timeout +06-Jul-19 22:35:31: INFO - Timeout +06-Jul-19 22:35:41: INFO - Timeout +06-Jul-19 22:35:51: INFO - Timeout +06-Jul-19 22:36:01: INFO - Timeout +06-Jul-19 22:36:11: INFO - Timeout +06-Jul-19 22:36:21: INFO - Timeout +06-Jul-19 22:36:31: INFO - Timeout +06-Jul-19 22:36:41: INFO - Timeout +06-Jul-19 22:36:51: INFO - Timeout +06-Jul-19 22:37:01: INFO - Timeout +06-Jul-19 22:37:11: INFO - Timeout +06-Jul-19 22:37:21: INFO - Timeout +06-Jul-19 22:37:31: INFO - Timeout +06-Jul-19 22:37:41: INFO - Timeout +06-Jul-19 22:37:51: INFO - Timeout +06-Jul-19 22:38:01: INFO - Timeout +06-Jul-19 22:38:11: INFO - Timeout +06-Jul-19 22:38:21: INFO - Timeout +06-Jul-19 22:38:31: INFO - Timeout +06-Jul-19 22:38:41: INFO - Timeout +06-Jul-19 22:38:51: INFO - Timeout +06-Jul-19 22:39:01: INFO - Timeout +06-Jul-19 22:39:11: INFO - Timeout +06-Jul-19 22:39:21: INFO - Timeout +06-Jul-19 22:39:31: INFO - Timeout +06-Jul-19 22:39:41: INFO - Timeout +06-Jul-19 22:39:51: INFO - Timeout +06-Jul-19 22:40:01: INFO - Timeout +06-Jul-19 22:40:11: INFO - Timeout +06-Jul-19 22:40:21: INFO - Timeout +06-Jul-19 22:40:31: INFO - Timeout +06-Jul-19 22:40:41: INFO - Timeout +06-Jul-19 22:40:51: INFO - Timeout +06-Jul-19 22:41:01: INFO - Timeout +06-Jul-19 22:41:11: INFO - Timeout +06-Jul-19 22:41:21: INFO - Timeout +06-Jul-19 22:41:31: INFO - Timeout +06-Jul-19 22:41:41: INFO - Timeout +06-Jul-19 22:41:51: INFO - Timeout +06-Jul-19 22:42:01: INFO - Timeout +06-Jul-19 22:42:11: INFO - Timeout +06-Jul-19 22:42:21: INFO - Timeout +06-Jul-19 22:42:31: INFO - Timeout +06-Jul-19 22:42:41: INFO - Timeout +06-Jul-19 22:42:51: INFO - Timeout +06-Jul-19 22:43:01: INFO - Timeout +06-Jul-19 22:43:11: INFO - Timeout +06-Jul-19 22:43:21: INFO - Timeout +06-Jul-19 22:43:31: INFO - Timeout +06-Jul-19 22:43:41: INFO - Timeout +06-Jul-19 22:43:51: INFO - Timeout +06-Jul-19 22:44:01: INFO - Timeout +06-Jul-19 22:44:11: INFO - Timeout +06-Jul-19 22:44:21: INFO - Timeout +06-Jul-19 22:44:31: INFO - Timeout +06-Jul-19 22:44:41: INFO - Timeout +06-Jul-19 22:44:51: INFO - Timeout +06-Jul-19 22:45:01: INFO - Timeout +06-Jul-19 22:45:11: INFO - Timeout +06-Jul-19 22:45:21: INFO - Timeout +06-Jul-19 22:45:31: INFO - Timeout +06-Jul-19 22:45:41: INFO - Timeout +06-Jul-19 22:45:51: INFO - Timeout +06-Jul-19 22:46:01: INFO - Timeout +06-Jul-19 22:46:11: INFO - Timeout +06-Jul-19 22:46:21: INFO - Timeout +06-Jul-19 22:46:31: INFO - Timeout +06-Jul-19 22:46:41: INFO - Timeout +06-Jul-19 22:46:51: INFO - Timeout +06-Jul-19 22:47:01: INFO - Timeout +06-Jul-19 22:47:11: INFO - Timeout +06-Jul-19 22:47:21: INFO - Timeout +06-Jul-19 22:47:31: INFO - Timeout +06-Jul-19 22:47:41: INFO - Timeout +06-Jul-19 22:47:51: INFO - Timeout +06-Jul-19 22:48:01: INFO - Timeout +06-Jul-19 22:48:11: INFO - Timeout +06-Jul-19 22:48:21: INFO - Timeout +06-Jul-19 22:48:31: INFO - Timeout +06-Jul-19 22:48:41: INFO - Timeout +06-Jul-19 22:48:51: INFO - Timeout +06-Jul-19 22:49:01: INFO - Timeout +06-Jul-19 22:49:11: INFO - Timeout +06-Jul-19 22:49:21: INFO - Timeout +06-Jul-19 22:49:31: INFO - Timeout +06-Jul-19 22:49:41: INFO - Timeout +06-Jul-19 22:49:51: INFO - Timeout +06-Jul-19 22:50:01: INFO - Timeout +06-Jul-19 22:50:11: INFO - Timeout +06-Jul-19 22:50:21: INFO - Timeout +06-Jul-19 22:50:31: INFO - Timeout +06-Jul-19 22:50:41: INFO - Timeout +06-Jul-19 22:50:51: INFO - Timeout +06-Jul-19 22:51:01: INFO - Timeout +06-Jul-19 22:51:11: INFO - Timeout +06-Jul-19 22:51:21: INFO - Timeout +06-Jul-19 22:51:31: INFO - Timeout +06-Jul-19 22:51:41: INFO - Timeout +06-Jul-19 22:51:51: INFO - Timeout +06-Jul-19 22:52:01: INFO - Timeout +06-Jul-19 22:52:11: INFO - Timeout +06-Jul-19 22:52:21: INFO - Timeout +06-Jul-19 22:52:31: INFO - Timeout +06-Jul-19 22:52:41: INFO - Timeout +06-Jul-19 22:52:51: INFO - Timeout +06-Jul-19 22:53:01: INFO - Timeout +06-Jul-19 22:53:11: INFO - Timeout +06-Jul-19 22:53:21: INFO - Timeout +06-Jul-19 22:53:31: INFO - Timeout +06-Jul-19 22:53:41: INFO - Timeout +06-Jul-19 22:53:51: INFO - Timeout +06-Jul-19 22:54:01: INFO - Timeout +06-Jul-19 22:54:11: INFO - Timeout +06-Jul-19 22:54:21: INFO - Timeout +06-Jul-19 22:54:31: INFO - Timeout +06-Jul-19 22:54:41: INFO - Timeout +06-Jul-19 22:54:51: INFO - Timeout +06-Jul-19 22:55:01: INFO - Timeout +06-Jul-19 22:55:11: INFO - Timeout +06-Jul-19 22:55:21: INFO - Timeout +06-Jul-19 22:55:31: INFO - Timeout +06-Jul-19 22:55:41: INFO - Timeout +06-Jul-19 22:55:51: INFO - Timeout +06-Jul-19 22:56:01: INFO - Timeout +06-Jul-19 22:56:11: INFO - Timeout +06-Jul-19 22:56:21: INFO - Timeout +06-Jul-19 22:56:31: INFO - Timeout +06-Jul-19 22:56:41: INFO - Timeout +06-Jul-19 22:56:51: INFO - Timeout +06-Jul-19 22:57:01: INFO - Timeout +06-Jul-19 22:57:11: INFO - Timeout +06-Jul-19 22:57:21: INFO - Timeout +06-Jul-19 22:57:31: INFO - Timeout +06-Jul-19 22:57:41: INFO - Timeout +06-Jul-19 22:57:51: INFO - Timeout +06-Jul-19 22:58:01: INFO - Timeout +06-Jul-19 22:58:11: INFO - Timeout +06-Jul-19 22:58:21: INFO - Timeout +06-Jul-19 22:58:31: INFO - Timeout +06-Jul-19 22:58:41: INFO - Timeout +06-Jul-19 22:58:51: INFO - Timeout +06-Jul-19 22:59:01: INFO - Timeout +06-Jul-19 22:59:11: INFO - Timeout +06-Jul-19 22:59:21: INFO - Timeout +06-Jul-19 22:59:31: INFO - Timeout +06-Jul-19 22:59:41: INFO - Timeout +06-Jul-19 22:59:51: INFO - Timeout +06-Jul-19 23:00:01: INFO - Timeout +06-Jul-19 23:00:11: INFO - Timeout +06-Jul-19 23:00:21: INFO - Timeout +06-Jul-19 23:00:31: INFO - Timeout +06-Jul-19 23:00:41: INFO - Timeout +06-Jul-19 23:00:51: INFO - Timeout +06-Jul-19 23:01:01: INFO - Timeout +06-Jul-19 23:01:11: INFO - Timeout +06-Jul-19 23:01:21: INFO - Timeout +06-Jul-19 23:01:31: INFO - Timeout +06-Jul-19 23:01:41: INFO - Timeout +06-Jul-19 23:01:51: INFO - Timeout +06-Jul-19 23:02:01: INFO - Timeout +06-Jul-19 23:02:11: INFO - Timeout +06-Jul-19 23:02:21: INFO - Timeout +06-Jul-19 23:02:31: INFO - Timeout +06-Jul-19 23:02:41: INFO - Timeout +06-Jul-19 23:02:51: INFO - Timeout +06-Jul-19 23:03:01: INFO - Timeout +06-Jul-19 23:03:11: INFO - Timeout +06-Jul-19 23:03:21: INFO - Timeout +06-Jul-19 23:03:31: INFO - Timeout +06-Jul-19 23:03:41: INFO - Timeout +06-Jul-19 23:03:51: INFO - Timeout +06-Jul-19 23:04:01: INFO - Timeout +06-Jul-19 23:04:11: INFO - Timeout +06-Jul-19 23:04:21: INFO - Timeout +06-Jul-19 23:04:31: INFO - Timeout +06-Jul-19 23:04:41: INFO - Timeout +06-Jul-19 23:04:51: INFO - Timeout +06-Jul-19 23:05:01: INFO - Timeout +06-Jul-19 23:05:11: INFO - Timeout +06-Jul-19 23:05:21: INFO - Timeout +06-Jul-19 23:05:31: INFO - Timeout +06-Jul-19 23:05:41: INFO - Timeout +06-Jul-19 23:05:51: INFO - Timeout +06-Jul-19 23:06:01: INFO - Timeout +06-Jul-19 23:06:11: INFO - Timeout +06-Jul-19 23:06:21: INFO - Timeout +06-Jul-19 23:06:31: INFO - Timeout +06-Jul-19 23:06:41: INFO - Timeout +06-Jul-19 23:06:51: INFO - Timeout +06-Jul-19 23:07:01: INFO - Timeout +06-Jul-19 23:07:11: INFO - Timeout +06-Jul-19 23:07:21: INFO - Timeout +06-Jul-19 23:07:31: INFO - Timeout +06-Jul-19 23:07:41: INFO - Timeout +06-Jul-19 23:07:51: INFO - Timeout +06-Jul-19 23:08:01: INFO - Timeout +06-Jul-19 23:08:11: INFO - Timeout +06-Jul-19 23:08:21: INFO - Timeout +06-Jul-19 23:08:31: INFO - Timeout +06-Jul-19 23:08:41: INFO - Timeout +06-Jul-19 23:08:51: INFO - Timeout +06-Jul-19 23:09:01: INFO - Timeout +06-Jul-19 23:09:11: INFO - Timeout +06-Jul-19 23:09:21: INFO - Timeout +06-Jul-19 23:09:31: INFO - Timeout +06-Jul-19 23:09:41: INFO - Timeout +06-Jul-19 23:09:51: INFO - Timeout +06-Jul-19 23:10:01: INFO - Timeout +06-Jul-19 23:10:11: INFO - Timeout +06-Jul-19 23:10:21: INFO - Timeout +06-Jul-19 23:10:31: INFO - Timeout +06-Jul-19 23:10:41: INFO - Timeout +06-Jul-19 23:10:51: INFO - Timeout +06-Jul-19 23:11:01: INFO - Timeout +06-Jul-19 23:11:11: INFO - Timeout +06-Jul-19 23:11:21: INFO - Timeout +06-Jul-19 23:11:31: INFO - Timeout +06-Jul-19 23:11:41: INFO - Timeout +06-Jul-19 23:11:51: INFO - Timeout +06-Jul-19 23:12:01: INFO - Timeout +06-Jul-19 23:12:11: INFO - Timeout +06-Jul-19 23:12:21: INFO - Timeout +06-Jul-19 23:12:31: INFO - Timeout +06-Jul-19 23:12:41: INFO - Timeout +06-Jul-19 23:12:51: INFO - Timeout +06-Jul-19 23:13:01: INFO - Timeout +06-Jul-19 23:13:11: INFO - Timeout +06-Jul-19 23:13:21: INFO - Timeout +06-Jul-19 23:13:31: INFO - Timeout +06-Jul-19 23:13:41: INFO - Timeout +06-Jul-19 23:13:51: INFO - Timeout +06-Jul-19 23:14:01: INFO - Timeout +06-Jul-19 23:14:11: INFO - Timeout +06-Jul-19 23:14:21: INFO - Timeout +06-Jul-19 23:14:31: INFO - Timeout +06-Jul-19 23:14:41: INFO - Timeout +06-Jul-19 23:14:51: INFO - Timeout +06-Jul-19 23:15:01: INFO - Timeout +06-Jul-19 23:15:11: INFO - Timeout +06-Jul-19 23:15:21: INFO - Timeout +06-Jul-19 23:15:31: INFO - Timeout +06-Jul-19 23:15:41: INFO - Timeout +06-Jul-19 23:15:51: INFO - Timeout +06-Jul-19 23:16:01: INFO - Timeout +06-Jul-19 23:16:11: INFO - Timeout +06-Jul-19 23:16:21: INFO - Timeout +06-Jul-19 23:16:31: INFO - Timeout +06-Jul-19 23:16:41: INFO - Timeout +06-Jul-19 23:16:51: INFO - Timeout +06-Jul-19 23:17:01: INFO - Timeout +06-Jul-19 23:17:11: INFO - Timeout +06-Jul-19 23:17:21: INFO - Timeout +06-Jul-19 23:17:31: INFO - Timeout +06-Jul-19 23:17:41: INFO - Timeout +06-Jul-19 23:17:51: INFO - Timeout +06-Jul-19 23:18:01: INFO - Timeout +06-Jul-19 23:18:11: INFO - Timeout +06-Jul-19 23:18:21: INFO - Timeout +06-Jul-19 23:18:31: INFO - Timeout +06-Jul-19 23:18:41: INFO - Timeout +06-Jul-19 23:18:51: INFO - Timeout +06-Jul-19 23:19:01: INFO - Timeout +06-Jul-19 23:19:11: INFO - Timeout +06-Jul-19 23:19:21: INFO - Timeout +06-Jul-19 23:19:31: INFO - Timeout +06-Jul-19 23:19:41: INFO - Timeout +06-Jul-19 23:19:51: INFO - Timeout +06-Jul-19 23:20:01: INFO - Timeout +06-Jul-19 23:20:11: INFO - Timeout +06-Jul-19 23:20:21: INFO - Timeout +06-Jul-19 23:20:31: INFO - Timeout +06-Jul-19 23:20:41: INFO - Timeout +06-Jul-19 23:20:51: INFO - Timeout +06-Jul-19 23:21:01: INFO - Timeout +06-Jul-19 23:21:11: INFO - Timeout +06-Jul-19 23:21:21: INFO - Timeout +06-Jul-19 23:21:31: INFO - Timeout +06-Jul-19 23:21:41: INFO - Timeout +06-Jul-19 23:21:51: INFO - Timeout +06-Jul-19 23:22:01: INFO - Timeout +06-Jul-19 23:22:11: INFO - Timeout +06-Jul-19 23:22:21: INFO - Timeout +06-Jul-19 23:22:31: INFO - Timeout +06-Jul-19 23:22:41: INFO - Timeout +06-Jul-19 23:22:51: INFO - Timeout +06-Jul-19 23:23:01: INFO - Timeout +06-Jul-19 23:23:11: INFO - Timeout +06-Jul-19 23:23:21: INFO - Timeout +06-Jul-19 23:23:31: INFO - Timeout +06-Jul-19 23:23:41: INFO - Timeout +06-Jul-19 23:23:51: INFO - Timeout +11-Jul-19 12:33:07: INFO - Timeout +11-Jul-19 12:33:17: INFO - Timeout +11-Jul-19 12:33:27: INFO - Timeout +11-Jul-19 12:33:37: INFO - Timeout +11-Jul-19 12:33:47: INFO - Timeout +11-Jul-19 12:33:57: INFO - Timeout +11-Jul-19 12:34:07: INFO - Timeout +11-Jul-19 12:34:17: INFO - Timeout +11-Jul-19 12:34:27: INFO - Timeout +11-Jul-19 12:34:37: INFO - Timeout +11-Jul-19 12:34:45: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:34:45: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:34:45: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpo4j4ttkj/qemu-8611-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 12:34:45: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:34:45: DEBUG - <<< {'return': {}} +11-Jul-19 12:34:45: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:34:45: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:34:45: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:34:45: INFO - Unknown Request +11-Jul-19 12:34:45: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:34:47: INFO - Timeout +11-Jul-19 12:34:57: INFO - Timeout +11-Jul-19 12:35:07: INFO - Timeout +11-Jul-19 12:35:17: INFO - Timeout +11-Jul-19 12:35:27: INFO - Timeout +11-Jul-19 12:35:37: INFO - Timeout +11-Jul-19 12:35:43: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:35:43: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:35:43: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp2e0c4s_g/qemu-8611-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +11-Jul-19 12:35:43: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:35:43: DEBUG - <<< {'return': {}} +11-Jul-19 12:35:43: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:35:43: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:35:43: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:35:43: INFO - Unknown Request +11-Jul-19 12:35:43: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:35:47: INFO - Timeout +11-Jul-19 12:35:57: INFO - Timeout +11-Jul-19 12:36:07: INFO - Timeout +11-Jul-19 12:36:17: INFO - Timeout +11-Jul-19 12:36:25: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:36:25: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:36:25: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:36:25: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:36:25: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:36:25: INFO - Unknown Request +11-Jul-19 12:36:25: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:36:27: INFO - Timeout +11-Jul-19 12:36:37: INFO - Timeout +11-Jul-19 12:36:45: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:36:45: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:36:45: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:36:45: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:36:45: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:36:45: INFO - Unknown Request +11-Jul-19 12:36:45: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:36:47: INFO - Timeout +11-Jul-19 12:36:57: INFO - Timeout +11-Jul-19 12:37:07: INFO - Timeout +11-Jul-19 12:37:17: INFO - Timeout +11-Jul-19 12:37:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:37:22: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:37:22: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:37:22: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:37:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:37:22: INFO - Unknown Request +11-Jul-19 12:37:22: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:37:27: INFO - Timeout +11-Jul-19 12:37:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:37:34: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:37:34: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:37:34: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:37:34: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:37:34: INFO - Unknown Request +11-Jul-19 12:37:34: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:37:37: INFO - Timeout +11-Jul-19 12:37:47: INFO - Timeout +11-Jul-19 12:37:57: INFO - Timeout +11-Jul-19 12:38:07: INFO - Timeout +11-Jul-19 12:38:17: INFO - Timeout +11-Jul-19 12:38:27: INFO - Timeout +11-Jul-19 12:38:37: INFO - Timeout +11-Jul-19 12:38:47: INFO - Timeout +11-Jul-19 12:38:57: INFO - Timeout +11-Jul-19 12:39:07: INFO - Timeout +11-Jul-19 12:39:17: INFO - Timeout +11-Jul-19 12:39:27: INFO - Timeout +11-Jul-19 12:39:37: INFO - Timeout +11-Jul-19 12:39:47: INFO - Timeout +11-Jul-19 12:39:57: INFO - Timeout +11-Jul-19 12:40:07: INFO - Timeout +11-Jul-19 12:40:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:40:19: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:40:19: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:40:19: INFO - Running VMs [] +11-Jul-19 12:40:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:40:19: INFO - Unknown Request +11-Jul-19 12:40:19: INFO - Running VMs [] +11-Jul-19 12:40:26: INFO - Timeout +11-Jul-19 12:40:26: DEBUG - None /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:40:26: INFO - /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running but is said to be running +11-Jul-19 12:40:26: INFO - Updating /v1/vm/726ce8bdd289436da4a647dac79fd027 status to KILLED +11-Jul-19 12:40:26: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:40:26: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +11-Jul-19 12:40:26: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +11-Jul-19 12:40:36: INFO - Timeout +11-Jul-19 12:40:46: INFO - Timeout +11-Jul-19 12:40:56: INFO - Timeout +11-Jul-19 12:41:06: INFO - Timeout +11-Jul-19 12:41:16: INFO - Timeout +11-Jul-19 12:41:26: INFO - Timeout +11-Jul-19 12:41:36: INFO - Timeout +11-Jul-19 12:41:46: INFO - Timeout +11-Jul-19 12:41:51: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:41:51: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:41:51: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp01eqmq4o/qemu-8963-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 12:41:51: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:41:51: DEBUG - <<< {'return': {}} +11-Jul-19 12:41:51: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:41:51: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:41:51: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:41:51: INFO - Unknown Request +11-Jul-19 12:41:51: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:41:56: INFO - Timeout +11-Jul-19 12:42:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:42:00: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:42:00: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmppfzgmjcu/qemu-8963-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +11-Jul-19 12:42:00: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:42:00: DEBUG - <<< {'return': {}} +11-Jul-19 12:42:00: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:42:00: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:42:00: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:42:00: INFO - Unknown Request +11-Jul-19 12:42:00: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:42:06: INFO - Timeout +11-Jul-19 12:42:09: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +11-Jul-19 12:42:09: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 12:42:09: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmptdocnqb_/qemu-8963-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :2' +11-Jul-19 12:42:09: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:42:09: DEBUG - <<< {'return': {}} +11-Jul-19 12:42:09: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 12:42:09: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:42:09: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +11-Jul-19 12:42:09: INFO - Unknown Request +11-Jul-19 12:42:09: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:42:16: INFO - Timeout +11-Jul-19 12:42:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:42:19: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:42:19: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:42:19: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:42:19: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:42:19: INFO - Unknown Request +11-Jul-19 12:42:19: INFO - Running VMs [VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:42:26: INFO - Timeout +11-Jul-19 12:42:36: INFO - Timeout +11-Jul-19 12:42:46: INFO - Timeout +11-Jul-19 12:42:56: INFO - Timeout +11-Jul-19 12:43:06: INFO - Timeout +11-Jul-19 12:43:16: INFO - Timeout +11-Jul-19 12:43:26: INFO - Timeout +11-Jul-19 12:43:36: INFO - Timeout +11-Jul-19 12:43:46: INFO - Timeout +11-Jul-19 12:43:56: INFO - Timeout +11-Jul-19 12:44:06: INFO - Timeout +11-Jul-19 12:44:16: INFO - Timeout +11-Jul-19 12:44:26: INFO - Timeout +11-Jul-19 12:44:36: INFO - Timeout +11-Jul-19 12:44:46: INFO - Timeout +11-Jul-19 12:44:56: INFO - Timeout +11-Jul-19 12:45:06: INFO - Timeout +11-Jul-19 12:45:16: INFO - Timeout +11-Jul-19 12:45:26: INFO - Timeout +11-Jul-19 12:45:36: INFO - Timeout +11-Jul-19 12:45:46: INFO - Timeout +11-Jul-19 12:45:56: INFO - Timeout +11-Jul-19 12:46:06: INFO - Timeout +11-Jul-19 12:46:16: INFO - Timeout +11-Jul-19 12:46:33: INFO - Timeout +11-Jul-19 12:46:33: DEBUG - None /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:46:33: INFO - /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running but is said to be running +11-Jul-19 12:46:33: INFO - Updating /v1/vm/726ce8bdd289436da4a647dac79fd027 status to KILLED +11-Jul-19 12:46:33: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:46:33: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +11-Jul-19 12:46:33: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +11-Jul-19 12:46:33: DEBUG - None /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 12:46:33: INFO - /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 is not running but is said to be running +11-Jul-19 12:46:33: INFO - Updating /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 status to KILLED +11-Jul-19 12:46:43: INFO - Timeout +11-Jul-19 12:46:53: INFO - Timeout +11-Jul-19 12:47:03: INFO - Timeout +11-Jul-19 12:47:13: INFO - Timeout +11-Jul-19 12:47:30: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:47:30: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:47:30: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:47:30: INFO - Running VMs [] +11-Jul-19 12:47:30: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:47:30: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:47:30: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:47:30: DEBUG - EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value='{"owner": "ahmedbilal-admin", "specs": {"cpu": 2, "ram": 2000000000}, "hostname": "/v1/host/1", "status": "DELETED", "image_uuid": "e6ce0f47-f99c-4ba9-b554-44910c81884e"}') +11-Jul-19 12:47:30: INFO - Running VMs [] +11-Jul-19 12:47:33: INFO - Timeout +11-Jul-19 12:47:43: INFO - Timeout +11-Jul-19 12:47:53: INFO - Timeout +11-Jul-19 12:48:06: INFO - Timeout +11-Jul-19 12:48:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:48:08: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:48:08: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:48:08: INFO - Running VMs [] +11-Jul-19 12:48:08: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:48:08: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:48:08: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:48:08: DEBUG - EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value='{"owner": "ahmedbilal-admin", "specs": {"cpu": 2, "ram": 2000000000}, "hostname": "/v1/host/1", "status": "DELETED", "image_uuid": "e6ce0f47-f99c-4ba9-b554-44910c81884e"}') +11-Jul-19 12:48:08: INFO - Running VMs [] +11-Jul-19 12:48:16: INFO - Timeout +11-Jul-19 12:48:26: INFO - Timeout +11-Jul-19 12:48:36: INFO - Timeout +11-Jul-19 12:48:46: INFO - Timeout +11-Jul-19 12:48:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:48:52: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:48:52: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:48:52: INFO - Running VMs [] +11-Jul-19 12:48:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:48:52: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:48:52: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:48:52: DEBUG - VM Info EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value='{"owner": "ahmedbilal-admin", "specs": {"cpu": 2, "ram": 2000000000}, "hostname": "/v1/host/1", "status": "DELETED", "image_uuid": "e6ce0f47-f99c-4ba9-b554-44910c81884e"}') +11-Jul-19 12:48:52: INFO - Running VMs [] +11-Jul-19 12:48:58: INFO - Timeout +11-Jul-19 12:49:08: INFO - Timeout +11-Jul-19 12:49:18: INFO - Timeout +11-Jul-19 12:49:28: INFO - Timeout +11-Jul-19 12:49:38: INFO - Timeout +11-Jul-19 12:49:48: INFO - Timeout +11-Jul-19 12:49:58: INFO - Timeout +11-Jul-19 12:50:08: INFO - Timeout +11-Jul-19 12:50:18: INFO - Timeout +11-Jul-19 12:50:28: INFO - Timeout +11-Jul-19 12:50:40: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:50:40: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:50:40: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:50:40: INFO - Running VMs [] +11-Jul-19 12:50:40: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:50:40: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:50:40: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:50:40: DEBUG - VM Info EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:50:40: INFO - Running VMs [] +11-Jul-19 12:50:47: INFO - Timeout +11-Jul-19 12:50:57: INFO - Timeout +11-Jul-19 12:51:07: INFO - Timeout +11-Jul-19 12:51:17: INFO - Timeout +11-Jul-19 12:51:27: INFO - Timeout +11-Jul-19 12:51:37: INFO - Timeout +11-Jul-19 12:51:47: INFO - Timeout +11-Jul-19 12:51:57: INFO - Timeout +11-Jul-19 12:52:07: INFO - Timeout +11-Jul-19 12:52:17: INFO - Timeout +11-Jul-19 12:52:27: INFO - Timeout +11-Jul-19 12:52:37: INFO - Timeout +11-Jul-19 12:52:47: INFO - Timeout +11-Jul-19 12:52:57: INFO - Timeout +11-Jul-19 12:53:07: INFO - Timeout +11-Jul-19 12:53:17: INFO - Timeout +11-Jul-19 12:53:27: INFO - Timeout +11-Jul-19 12:53:37: INFO - Timeout +11-Jul-19 12:53:47: INFO - Timeout +11-Jul-19 12:53:57: INFO - Timeout +11-Jul-19 12:54:07: INFO - Timeout +11-Jul-19 12:54:17: INFO - Timeout +11-Jul-19 12:54:27: INFO - Timeout +11-Jul-19 12:54:37: INFO - Timeout +11-Jul-19 12:54:47: INFO - Timeout +11-Jul-19 12:54:57: INFO - Timeout +11-Jul-19 12:55:07: INFO - Timeout +11-Jul-19 12:55:17: INFO - Timeout +11-Jul-19 12:55:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:55:23: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:55:23: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:55:23: INFO - Running VMs [] +11-Jul-19 12:55:23: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:55:23: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:55:23: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:55:23: INFO - Running VMs [] +11-Jul-19 12:55:30: INFO - Timeout +11-Jul-19 12:55:40: INFO - Timeout +11-Jul-19 12:55:50: INFO - Timeout +11-Jul-19 12:56:00: INFO - Timeout +11-Jul-19 12:56:05: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:56:05: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:56:05: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:56:05: INFO - Running VMs [] +11-Jul-19 12:56:05: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:56:05: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:56:05: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:56:05: INFO - Running VMs [] +11-Jul-19 12:56:10: INFO - Timeout +11-Jul-19 12:56:20: INFO - Timeout +11-Jul-19 12:56:29: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:56:29: INFO - Image file of vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 does not exists +11-Jul-19 12:56:29: INFO - Setting vm /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 status to DELETED +11-Jul-19 12:56:29: INFO - Running VMs [] +11-Jul-19 12:56:29: DEBUG - EVENT: EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:56:29: INFO - Deleting VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 +11-Jul-19 12:56:29: INFO - shutdown_vm failed because VM /v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135 is not running +11-Jul-19 12:56:29: INFO - VM EtcdEntry(key='/v1/vm/dee8a10d6fc94d60a2b55c2dc0ff6135', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'DELETED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) deleted +11-Jul-19 12:56:29: INFO - Running VMs [] +11-Jul-19 12:56:29: ERROR - Invalid JSON +11-Jul-19 12:56:35: INFO - Timeout +11-Jul-19 12:56:45: INFO - Timeout +11-Jul-19 12:56:55: INFO - Timeout +11-Jul-19 12:57:05: INFO - Timeout +11-Jul-19 12:57:15: INFO - Timeout +11-Jul-19 12:57:25: INFO - Timeout +11-Jul-19 12:57:26: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +11-Jul-19 12:57:26: INFO - Starting /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 12:57:26: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpibycyvbb/qemu-9610-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/d74c7dc585fb4c31ab8df6b010e12594.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 12:57:26: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:57:26: DEBUG - <<< {'return': {}} +11-Jul-19 12:57:26: INFO - Started Successfully /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 12:57:26: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:57:26: DEBUG - EVENT: EtcdEntry(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 12, 'hdd': 20, 'sdd': 0}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'b8719f3a-05a3-4f59-bb5f-8421de947b92'}) +11-Jul-19 12:57:26: INFO - Unknown Request +11-Jul-19 12:57:26: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=)] +11-Jul-19 12:57:35: INFO - Timeout +11-Jul-19 12:57:39: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:57:39: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:57:39: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpl1iuifbp/qemu-9610-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +11-Jul-19 12:57:39: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:57:39: DEBUG - <<< {'return': {}} +11-Jul-19 12:57:39: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 12:57:39: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:57:39: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:57:39: INFO - Unknown Request +11-Jul-19 12:57:39: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 12:57:45: INFO - Timeout +11-Jul-19 12:57:47: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:57:47: INFO - Starting /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:57:47: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpgkpgsw61/qemu-9610-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/726ce8bdd289436da4a647dac79fd027.raw -boot c -net nic -net user -m 1024 -vnc :2' +11-Jul-19 12:57:47: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 12:57:47: DEBUG - <<< {'return': {}} +11-Jul-19 12:57:47: INFO - Started Successfully /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 12:57:47: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:57:47: DEBUG - EVENT: EtcdEntry(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 12:57:47: INFO - Unknown Request +11-Jul-19 12:57:47: INFO - Running VMs [VM(key='/v1/vm/d74c7dc585fb4c31ab8df6b010e12594', vm=), VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=), VM(key='/v1/vm/726ce8bdd289436da4a647dac79fd027', vm=)] +11-Jul-19 12:57:55: INFO - Timeout +11-Jul-19 12:58:05: INFO - Timeout +11-Jul-19 12:58:15: INFO - Timeout +11-Jul-19 12:58:25: INFO - Timeout +11-Jul-19 12:58:35: INFO - Timeout +11-Jul-19 12:58:46: INFO - Timeout +11-Jul-19 12:58:56: INFO - Timeout +11-Jul-19 12:59:06: INFO - Timeout +11-Jul-19 12:59:16: INFO - Timeout +11-Jul-19 12:59:26: INFO - Timeout +11-Jul-19 12:59:36: INFO - Timeout +11-Jul-19 12:59:46: INFO - Timeout +11-Jul-19 12:59:56: INFO - Timeout +11-Jul-19 13:00:07: INFO - Timeout +11-Jul-19 13:00:07: DEBUG - None /v1/vm/726ce8bdd289436da4a647dac79fd027 +11-Jul-19 13:00:07: INFO - /v1/vm/726ce8bdd289436da4a647dac79fd027 is not running but is said to be running +11-Jul-19 13:00:07: INFO - Updating /v1/vm/726ce8bdd289436da4a647dac79fd027 status to KILLED +11-Jul-19 13:00:07: DEBUG - None /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:00:07: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running but is said to be running +11-Jul-19 13:00:07: INFO - Updating /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a status to KILLED +11-Jul-19 13:00:07: DEBUG - None /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 +11-Jul-19 13:00:07: INFO - /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 is not running but is said to be running +11-Jul-19 13:00:07: INFO - Updating /v1/vm/d74c7dc585fb4c31ab8df6b010e12594 status to KILLED +11-Jul-19 13:00:17: INFO - Timeout +11-Jul-19 13:00:27: INFO - Timeout +11-Jul-19 13:00:37: INFO - Timeout +11-Jul-19 13:00:47: INFO - Timeout +11-Jul-19 13:00:57: INFO - Timeout +11-Jul-19 13:01:05: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:01:05: INFO - suspend_vm failed because VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running +11-Jul-19 13:01:05: INFO - Running VMs [] +11-Jul-19 13:01:08: INFO - Timeout +11-Jul-19 13:01:18: INFO - Timeout +11-Jul-19 13:01:28: INFO - Timeout +11-Jul-19 13:01:38: INFO - Timeout +11-Jul-19 13:01:48: INFO - Timeout +11-Jul-19 13:01:58: INFO - Timeout +11-Jul-19 13:02:08: INFO - Timeout +11-Jul-19 13:02:18: INFO - Timeout +11-Jul-19 13:02:28: INFO - Timeout +11-Jul-19 13:02:38: INFO - Timeout +11-Jul-19 13:02:48: INFO - Timeout +11-Jul-19 13:02:58: INFO - Timeout +11-Jul-19 13:03:08: INFO - Timeout +11-Jul-19 13:03:18: INFO - Timeout +11-Jul-19 13:03:28: INFO - Timeout +11-Jul-19 13:03:38: INFO - Timeout +11-Jul-19 13:03:48: INFO - Timeout +11-Jul-19 13:03:58: INFO - Timeout +11-Jul-19 13:04:08: INFO - Timeout +11-Jul-19 13:04:18: INFO - Timeout +11-Jul-19 13:04:28: INFO - Timeout +11-Jul-19 13:04:38: INFO - Timeout +11-Jul-19 13:04:48: INFO - Timeout +11-Jul-19 13:04:58: INFO - Timeout +11-Jul-19 13:05:08: INFO - Timeout +11-Jul-19 13:05:18: INFO - Timeout +11-Jul-19 13:05:28: INFO - Timeout +11-Jul-19 13:05:38: INFO - Timeout +11-Jul-19 13:05:48: INFO - Timeout +11-Jul-19 13:05:58: INFO - Timeout +11-Jul-19 13:06:08: INFO - Timeout +11-Jul-19 13:06:18: INFO - Timeout +11-Jul-19 13:06:28: INFO - Timeout +11-Jul-19 13:06:38: INFO - Timeout +11-Jul-19 13:06:48: INFO - Timeout +11-Jul-19 13:06:58: INFO - Timeout +11-Jul-19 13:07:08: INFO - Timeout +11-Jul-19 13:07:18: INFO - Timeout +11-Jul-19 13:07:28: INFO - Timeout +11-Jul-19 13:07:38: INFO - Timeout +11-Jul-19 13:07:48: INFO - Timeout +11-Jul-19 13:07:58: INFO - Timeout +11-Jul-19 13:08:08: INFO - Timeout +11-Jul-19 13:08:18: INFO - Timeout +11-Jul-19 13:08:28: INFO - Timeout +11-Jul-19 13:08:38: INFO - Timeout +11-Jul-19 13:08:48: INFO - Timeout +11-Jul-19 13:08:58: INFO - Timeout +11-Jul-19 13:09:08: INFO - Timeout +11-Jul-19 13:09:18: INFO - Timeout +11-Jul-19 13:09:28: INFO - Timeout +11-Jul-19 13:09:38: INFO - Timeout +11-Jul-19 13:09:48: INFO - Timeout +11-Jul-19 13:09:58: INFO - Timeout +11-Jul-19 13:10:08: INFO - Timeout +11-Jul-19 13:10:18: INFO - Timeout +11-Jul-19 13:10:28: INFO - Timeout +11-Jul-19 13:10:38: INFO - Timeout +11-Jul-19 13:10:48: INFO - Timeout +11-Jul-19 13:10:58: INFO - Timeout +11-Jul-19 13:11:08: INFO - Timeout +11-Jul-19 13:11:18: INFO - Timeout +11-Jul-19 13:11:28: INFO - Timeout +11-Jul-19 13:11:38: INFO - Timeout +11-Jul-19 13:11:48: INFO - Timeout +11-Jul-19 13:11:58: INFO - Timeout +11-Jul-19 13:12:08: INFO - Timeout +11-Jul-19 13:12:18: INFO - Timeout +11-Jul-19 13:12:28: INFO - Timeout +11-Jul-19 13:12:38: INFO - Timeout +11-Jul-19 13:12:48: INFO - Timeout +11-Jul-19 13:12:58: INFO - Timeout +11-Jul-19 13:13:08: INFO - Timeout +11-Jul-19 13:13:18: INFO - Timeout +11-Jul-19 13:13:28: INFO - Timeout +11-Jul-19 13:13:38: INFO - Timeout +11-Jul-19 13:13:48: INFO - Timeout +11-Jul-19 13:13:58: INFO - Timeout +11-Jul-19 13:14:08: INFO - Timeout +11-Jul-19 13:14:18: INFO - Timeout +11-Jul-19 13:14:28: INFO - Timeout +11-Jul-19 13:14:38: INFO - Timeout +11-Jul-19 13:14:48: INFO - Timeout +11-Jul-19 13:14:58: INFO - Timeout +11-Jul-19 13:15:08: INFO - Timeout +11-Jul-19 13:15:18: INFO - Timeout +11-Jul-19 13:15:28: INFO - Timeout +11-Jul-19 13:15:38: INFO - Timeout +11-Jul-19 13:15:48: INFO - Timeout +11-Jul-19 13:15:58: INFO - Timeout +11-Jul-19 13:16:08: INFO - Timeout +11-Jul-19 13:16:18: INFO - Timeout +11-Jul-19 13:16:28: INFO - Timeout +11-Jul-19 13:16:38: INFO - Timeout +11-Jul-19 13:16:48: INFO - Timeout +11-Jul-19 13:16:58: INFO - Timeout +11-Jul-19 13:17:08: INFO - Timeout +11-Jul-19 13:17:18: INFO - Timeout +11-Jul-19 13:17:28: INFO - Timeout +11-Jul-19 13:17:38: INFO - Timeout +11-Jul-19 13:17:48: INFO - Timeout +11-Jul-19 13:17:58: INFO - Timeout +11-Jul-19 13:18:08: INFO - Timeout +11-Jul-19 13:18:18: INFO - Timeout +11-Jul-19 13:18:28: INFO - Timeout +11-Jul-19 13:18:38: INFO - Timeout +11-Jul-19 13:18:48: INFO - Timeout +11-Jul-19 13:18:58: INFO - Timeout +11-Jul-19 13:19:08: INFO - Timeout +11-Jul-19 13:19:18: INFO - Timeout +11-Jul-19 13:19:32: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:19:32: INFO - suspend_vm failed because VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running +11-Jul-19 13:19:32: INFO - Running VMs [] +11-Jul-19 13:19:39: INFO - Timeout +11-Jul-19 13:19:47: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:19:47: INFO - resume_vm failed because VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running +11-Jul-19 13:19:47: INFO - Running VMs [] +11-Jul-19 13:19:49: INFO - Timeout +11-Jul-19 13:19:59: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SHUTDOWN', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:19:59: INFO - shutdown_vm failed because VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a is not running +11-Jul-19 13:19:59: INFO - Running VMs [] +11-Jul-19 13:19:59: INFO - Timeout +11-Jul-19 13:20:09: INFO - Timeout +11-Jul-19 13:20:19: INFO - Timeout +11-Jul-19 13:20:29: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:20:29: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:20:29: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpjsirh62a/qemu-9978-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 13:20:29: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 13:20:29: DEBUG - <<< {'return': {}} +11-Jul-19 13:20:29: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:20:29: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:20:29: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:20:29: INFO - Unknown Request +11-Jul-19 13:20:29: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:20:29: INFO - Timeout +11-Jul-19 13:20:39: INFO - Timeout +11-Jul-19 13:20:49: INFO - Timeout +11-Jul-19 13:20:59: INFO - Timeout +11-Jul-19 13:21:09: INFO - Timeout +11-Jul-19 13:21:19: INFO - Timeout +11-Jul-19 13:21:29: INFO - Timeout +11-Jul-19 13:21:39: INFO - Timeout +11-Jul-19 13:21:49: INFO - Timeout +11-Jul-19 13:21:59: INFO - Timeout +11-Jul-19 13:22:01: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_SUSPEND', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:01: DEBUG - >>> {'execute': 'query-status'} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833297, 'microseconds': 936113}, 'event': 'VNC_CONNECTED', 'data': {'server': {'auth': 'none', 'family': 'ipv4', 'service': '5900', 'host': '0.0.0.0', 'websocket': False}, 'client': {'family': 'ipv4', 'service': '53110', 'host': '127.0.0.1', 'websocket': False}}} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833297, 'microseconds': 937660}, 'event': 'VNC_INITIALIZED', 'data': {'server': {'auth': 'none', 'family': 'ipv4', 'service': '5900', 'host': '0.0.0.0', 'websocket': False}, 'client': {'family': 'ipv4', 'service': '53110', 'host': '127.0.0.1', 'websocket': False}}} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833303, 'microseconds': 241718}, 'event': 'VNC_DISCONNECTED', 'data': {'server': {'auth': 'none', 'family': 'ipv4', 'service': '5900', 'host': '0.0.0.0', 'websocket': False}, 'client': {'family': 'ipv4', 'service': '53110', 'host': '127.0.0.1', 'websocket': False}}} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833315, 'microseconds': 954999}, 'event': 'VNC_CONNECTED', 'data': {'server': {'auth': 'none', 'family': 'ipv4', 'service': '5900', 'host': '0.0.0.0', 'websocket': False}, 'client': {'family': 'ipv4', 'service': '53114', 'host': '127.0.0.1', 'websocket': False}}} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833315, 'microseconds': 956344}, 'event': 'VNC_INITIALIZED', 'data': {'server': {'auth': 'none', 'family': 'ipv4', 'service': '5900', 'host': '0.0.0.0', 'websocket': False}, 'client': {'family': 'ipv4', 'service': '53114', 'host': '127.0.0.1', 'websocket': False}}} +11-Jul-19 13:22:01: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +11-Jul-19 13:22:01: DEBUG - VM Status Check - {'status': 'running', 'singlestep': False, 'running': True} +11-Jul-19 13:22:01: DEBUG - >>> {'execute': 'stop'} +11-Jul-19 13:22:01: DEBUG - <<< {'timestamp': {'seconds': 1562833321, 'microseconds': 139114}, 'event': 'STOP'} +11-Jul-19 13:22:01: DEBUG - <<< {'return': {}} +11-Jul-19 13:22:01: DEBUG - >>> {'execute': 'query-status'} +11-Jul-19 13:22:01: DEBUG - <<< {'return': {'status': 'paused', 'singlestep': False, 'running': False}} +11-Jul-19 13:22:01: INFO - Successfully suspended VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:22:01: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:01: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'SUSPENDED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:01: INFO - Unknown Request +11-Jul-19 13:22:01: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:09: INFO - Timeout +11-Jul-19 13:22:13: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_RESUME', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:13: DEBUG - >>> {'execute': 'query-status'} +11-Jul-19 13:22:13: DEBUG - <<< {'return': {'status': 'paused', 'singlestep': False, 'running': False}} +11-Jul-19 13:22:13: DEBUG - VM Status Check - {'status': 'paused', 'singlestep': False, 'running': False} +11-Jul-19 13:22:13: DEBUG - >>> {'execute': 'cont'} +11-Jul-19 13:22:13: DEBUG - <<< {'timestamp': {'seconds': 1562833333, 'microseconds': 841383}, 'event': 'RESUME'} +11-Jul-19 13:22:13: DEBUG - <<< {'return': {}} +11-Jul-19 13:22:13: DEBUG - >>> {'execute': 'query-status'} +11-Jul-19 13:22:13: DEBUG - <<< {'return': {'status': 'running', 'singlestep': False, 'running': True}} +11-Jul-19 13:22:13: INFO - Successfully resumed VM /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:22:13: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:13: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:13: INFO - Unknown Request +11-Jul-19 13:22:13: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:19: INFO - Timeout +11-Jul-19 13:22:26: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:26: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:22:26: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpgmqqfvgo/qemu-9978-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +11-Jul-19 13:22:26: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 13:22:26: DEBUG - <<< None +11-Jul-19 13:22:26: DEBUG - Error launching VM +11-Jul-19 13:22:26: DEBUG - Command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpgmqqfvgo/qemu-9978-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :1' +11-Jul-19 13:22:26: DEBUG - Output: 'WARNING: Image format was not specified for \'/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw\' and probing guessed raw.\n Automatically detecting the format is dangerous for raw images, write operations on block 0 will be restricted.\n Specify the \'raw\' format explicitly to remove the restrictions.\nqemu-system-x86_64: Initialization of device ide-hd failed: Failed to get "write" lock\nIs another process using the image [/var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw]?\n' +11-Jul-19 13:22:26: INFO - Machine Error Occurred on /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:22:26: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:26: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'KILLED', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:22:26: INFO - Unknown Request +11-Jul-19 13:22:26: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:22:29: INFO - Timeout +11-Jul-19 13:22:39: INFO - Timeout +11-Jul-19 13:22:49: INFO - Timeout +11-Jul-19 13:22:59: INFO - Timeout +11-Jul-19 13:23:09: INFO - Timeout +11-Jul-19 13:23:19: INFO - Timeout +11-Jul-19 13:23:29: INFO - Timeout +11-Jul-19 13:23:39: INFO - Timeout +11-Jul-19 13:23:49: INFO - Timeout +11-Jul-19 13:23:59: INFO - Timeout +11-Jul-19 13:24:09: INFO - Timeout +11-Jul-19 13:24:19: INFO - Timeout +11-Jul-19 13:24:29: INFO - Timeout +11-Jul-19 13:24:39: INFO - Timeout +11-Jul-19 13:24:49: INFO - Timeout +11-Jul-19 13:24:59: INFO - Timeout +11-Jul-19 13:25:09: INFO - Timeout +11-Jul-19 13:25:27: INFO - Timeout +11-Jul-19 13:25:37: INFO - Timeout +11-Jul-19 13:25:38: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:25:38: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:25:38: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmpy73dsq_z/qemu-10222-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 13:25:38: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 13:25:38: DEBUG - <<< {'return': {}} +11-Jul-19 13:25:38: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:25:38: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:25:38: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:25:38: INFO - Unknown Request +11-Jul-19 13:25:38: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:25:41: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:25:41: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a already running +11-Jul-19 13:25:41: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:25:47: INFO - Timeout +11-Jul-19 13:25:52: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:25:52: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a already running +11-Jul-19 13:25:52: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:25:57: INFO - Timeout +11-Jul-19 13:26:07: INFO - Timeout +11-Jul-19 13:26:21: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:26:21: INFO - Starting /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:26:21: DEBUG - VM launch command: '/usr/bin/qemu-system-x86_64 -chardev socket,id=mon,path=vm_socklog/tmp6951kbcp/qemu-10319-monitor.sock -mon chardev=mon,mode=control -display none -vga none /var/www/ahmedbilal-admin/.vm/9809d194aca94a40b4ef3ad1a2d5d80a.raw -boot c -net nic -net user -m 1024 -vnc :0' +11-Jul-19 13:26:22: DEBUG - >>> {'execute': 'qmp_capabilities'} +11-Jul-19 13:26:22: DEBUG - <<< {'return': {}} +11-Jul-19 13:26:22: INFO - Started Successfully /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a +11-Jul-19 13:26:22: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:26:22: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:26:22: INFO - Unknown Request +11-Jul-19 13:26:22: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:26:26: INFO - Timeout +11-Jul-19 13:26:28: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'REQUESTED_START', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:26:28: INFO - /v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a already running +11-Jul-19 13:26:28: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:26:28: DEBUG - EVENT: EtcdEntry(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', value={'owner': 'ahmedbilal-admin', 'specs': {'cpu': 2, 'ram': 2000000000}, 'hostname': '/v1/host/1', 'status': 'RUNNING', 'image_uuid': 'e6ce0f47-f99c-4ba9-b554-44910c81884e'}) +11-Jul-19 13:26:28: INFO - Unknown Request +11-Jul-19 13:26:28: INFO - Running VMs [VM(key='/v1/vm/9809d194aca94a40b4ef3ad1a2d5d80a', vm=)] +11-Jul-19 13:26:36: INFO - Timeout +11-Jul-19 13:26:46: INFO - Timeout +11-Jul-19 13:26:56: INFO - Timeout +11-Jul-19 13:27:06: INFO - Timeout +11-Jul-19 13:27:16: INFO - Timeout +11-Jul-19 13:27:26: INFO - Timeout +11-Jul-19 13:27:36: INFO - Timeout +11-Jul-19 13:27:46: INFO - Timeout diff --git a/main.py b/main.py index 0dc00cd..6b167e8 100644 --- a/main.py +++ b/main.py @@ -1,127 +1,247 @@ # TODO -# 1. Verify that commands successfully did what they are -# supposed to do by querying state of VM using QMP. -# 2. Implement Monitoring of VM. - # For QEMU Monitor Protocol Commands Information, See # https://qemu.weilnetz.de/doc/qemu-doc.html#pcsys_005fmonitor import json -import shutil -import os -import subprocess import argparse import qmp +import logging +import os +import shutil from etcd3_wrapper import Etcd3Wrapper +from dataclasses import dataclass +from typing import Union +from functools import wraps from decouple import config +running_vms = [] +client = Etcd3Wrapper() -def get_vm_start_cmd(owner_dir, vm_uuid, vm=False): - vm_sock_flags = f"-qmp unix:{owner_dir}/.vm/{vm_uuid}-sock,server,nowait" - vm_start_command_flags = ( - f"-boot c -net nic -net user -m 256 {vm_sock_flags} -daemonize" + +@dataclass +class VM: + key: str + vm: qmp.QEMUMachine + + +logging.basicConfig( + level=logging.DEBUG, + filename="log.txt", + filemode="a", + format="%(asctime)s: %(levelname)s - %(message)s", + datefmt="%d-%b-%y %H:%M:%S", +) + + +def need_running_vm(func): + @wraps(func) + def wrapper(e): + vm = get_vm(running_vms, e.key) + if vm: + try: + status = vm.vm.command("query-status") + logging.debug(f"VM Status Check - {status}") + except OSError: + logging.info(f"{func.__name__} failed - VM {e.key} - Unknown Error") + + return func(e) + else: + logging.info(f"{func.__name__} failed because VM {e.key} is not running") + return + return wrapper + + +def create_vm(owner_dir, vm_uuid, e): + image = client.get( + f"/v1/image/{e.value['image_uuid']}", value_in_json=True ) - vm_start_command = f"""qemu-system-x86_64 {owner_dir}/.vm/{vm_uuid}.raw {vm_start_command_flags}""" - if vm: - vm_start_command += " -display none" - return vm_start_command + if image: + logging.debug(image) + image_uuid = e.value["image_uuid"] + + logging.info("Creating New VM...") + + os.makedirs(f"{owner_dir}/.vm", exist_ok=True) + + if not os.path.isfile(f"{owner_dir}/.vm/{vm_uuid}.raw"): + shutil.copy( + f"/var/vm/{image_uuid}.raw", f"{owner_dir}/.vm/{vm_uuid}.raw" + ) + + e.value["status"] = "REQUESTED_START" + client.put(e.key, json.dumps(e.value)) -def get_qemu_mon(sock_file): - m = qmp.QEMUMonitorProtocol(sock_file) +def start_vm(vm_path, e): + if not os.path.isfile(vm_path): + logging.info(f"Image file of vm {e.key} does not exists") + logging.info(f"Setting vm {e.key} status to DELETED") + e.value["status"] = "DELETED" + client.put(e.key, json.dumps(e.value)) + return + + _vm = get_vm(running_vms, e.key) + if _vm: + logging.info(f"{e.key} already running") + e.value["status"] = "RUNNING" + client.put(e.key, json.dumps(e.value)) + return + + vm = qmp.QEMUMachine( + "/usr/bin/qemu-system-x86_64", + test_dir="vm_socklog", + args=[ + vm_path, + "-boot", + "c", + "-net", + "nic", + "-net", + "user", + "-m", + "1024", + "-vnc", + f":{len(running_vms)}", + ], + ) try: - m.connect() - except FileNotFoundError as _: - return None - return m + logging.info(f"Starting {e.key}") + vm.launch() + if vm.is_running(): + running_vms.append(VM(e.key, vm)) + e.value["status"] = "RUNNING" + client.put(e.key, e.value, value_in_json=True) + else: + e.value["status"] = "KILLED" + client.put(e.key, e.value, value_in_json=True) + return + except (qmp.QEMUMachineError, TypeError): + logging.info(f"Machine Error Occurred on {e.key}") + e.value["status"] = "KILLED" + client.put(e.key, e.value, value_in_json=True) + else: + logging.info(f"Started Successfully {e.key}") -def main(hostname, is_vm): - client = Etcd3Wrapper() +@need_running_vm +def suspend_vm(e): + vm = get_vm(running_vms, e.key) + vm.vm.command("stop") + if vm.vm.command("query-status")["status"] == "paused": + e.value["status"] = "SUSPENDED" + client.put(e.key, json.dumps(e.value)) + logging.info(f"Successfully suspended VM {e.key}") + else: + logging.info(f"Suspending VM {e.key} failed") - events = client.watch_prefix("/v1/vm/") + +@need_running_vm +def resume_vm(e): + vm = get_vm(running_vms, e.key) + vm.vm.command("cont") + if vm.vm.command("query-status")["status"] == "running": + e.value["status"] = "RUNNING" + client.put(e.key, json.dumps(e.value)) + logging.info(f"Successfully resumed VM {e.key}") + else: + logging.info(f"Resuming VM {e.key} failed") + + +@need_running_vm +def shutdown_vm(e): + vm = get_vm(running_vms, e.key) + vm.vm.shutdown() + if not vm.vm.is_running(): + logging.info(f"VM {e.key} shutdown successfully") + e.value["status"] = "STOPPED" + client.put(e.key, json.dumps(e.value)) + running_vms.remove(vm) + + +def delete_vm(e): + logging.info(f"Deleting VM {e.key}") + shutdown_vm(e) + vm = client.get(e.key, value_in_json=True) + if vm: + vm_id = e.key.split('/')[-1] + vm_owner = e.value['owner'] + vm_path = f"{config('BASE_DIR')}/{vm_owner}/.vm/{vm_id}" + + if os.path.exists(vm_path): + os.remove(vm_path) + client.client.delete(e.key) + logging.info(f"VM {vm.key} deleted") + else: + logging.info(f"Cannot delete key {e.key} because it doesn't exists") + + +def get_vm(vm_list: list, vm_key) -> Union[VM, None]: + return next((vm for vm in vm_list if vm.key == vm_key), None) + + +def main(hostname): + events = client.watch_prefix("/v1/vm/", timeout=10) # events = client.get_prefix("/v1/vm/") for e in events: - e.value = json.loads(e.value) - e_hostname = e.value["hostname"] + try: + e.value = json.loads(e.value) + except json.JSONDecodeError: + logging.error(f"Invalid JSON {e.value}") + continue + e_status = e.value["status"] + + if e_status == "TIMEOUT": + logging.info("Timeout") + _vms = filter(lambda v: v.value["hostname"] == hostname, client.get_prefix("/v1/vm", value_in_json=True)) + alleged_running_vms = filter(lambda v: v.value["status"] == "RUNNING", _vms) + for vm in alleged_running_vms: + _vm = get_vm(running_vms, vm.key) + if (_vm and not _vm.vm.is_running()) or _vm is None: + logging.debug(f"{_vm} {vm.key}") + logging.info(f"{vm.key} is not running but is said to be running") + logging.info(f"Updating {vm.key} status to KILLED") + vm.value["status"] = "KILLED" + client.put(vm.key, json.dumps(vm.value)) + continue + + e_hostname = e.value["hostname"] vm_uuid = e.key.split("/")[-1] - owner_dir = f"/var/www/{e.value['owner']}" + owner_dir = f"{config('BASE_DIR')}/{e.value['owner']}" # If it is not for me then skip it if e_hostname != hostname: continue - print(e_status, e) + + logging.debug(f"EVENT: {e}") if e_status == "SCHEDULED_DEPLOY": - image = client.get( - f"/v1/image/{e.value['image_uuid']}", value_in_json=True - ) - if image: - image_uuid = e.value["image_uuid"] - print(image) - print("Creating New VM...") - - os.makedirs(f"{owner_dir}/.vm", exist_ok=True) - - if not os.path.isfile(f"{owner_dir}/.vm/{vm_uuid}.raw"): - shutil.copy( - f"/var/vm/{image_uuid}.raw", - f"{owner_dir}/.vm/{vm_uuid}.raw", - ) - - e.value["status"] = "REQUESTED_START" - client.put(e.key, json.dumps(e.value)) + create_vm(owner_dir, vm_uuid, e) elif e_status == "REQUESTED_SUSPEND": - m = get_qemu_mon(f"{owner_dir}/.vm/{vm_uuid}-sock") - - if m: - print("Suspending") - m.command("stop") - m.close() - e.value["status"] = "SUSPENDED" - client.put(e.key, json.dumps(e.value)) - else: - print("VM Not Running") + suspend_vm(e) elif e_status == "REQUESTED_RESUME": - m = get_qemu_mon(f"{owner_dir}/.vm/{vm_uuid}-sock") - if m: - print("Resuming") - - m.command("cont") - m.close() - - e.value["status"] = "RESUMED" - client.put(e.key, json.dumps(e.value)) - else: - print("VM Not Running") + resume_vm(e) elif e_status == "REQUESTED_START": - m = get_qemu_mon(f"{owner_dir}/.vm/{vm_uuid}-sock") - if m: - m.close() - print("VM already running") - e.value["status"] = "RUNNING" - client.put(e.key, e.value, value_in_json=True) - else: - print("Starting VM") - subprocess.run( - get_vm_start_cmd(owner_dir, vm_uuid, is_vm).split(" ") - ) - e.value["status"] = "RUNNING" - client.put(e.key, e.value, value_in_json=True) - else: - continue + vm_path = f"{owner_dir}/.vm/{vm_uuid}.raw" + start_vm(vm_path, e) + + elif e_status == "REQUESTED_SHUTDOWN": + shutdown_vm(e) + + elif e_status == "DELETED": + delete_vm(e) + + logging.info(f"Running VMs {running_vms}") argparser = argparse.ArgumentParser() argparser.add_argument("hostname", help="Name of this host. e.g /v1/host/1") -argparser.add_argument("--vm", type=bool, default=False) args = argparser.parse_args() -main(args.hostname, args.vm) +main(args.hostname) diff --git a/qmp/__init__.py b/qmp/__init__.py new file mode 100644 index 0000000..117e2ed --- /dev/null +++ b/qmp/__init__.py @@ -0,0 +1,535 @@ +# QEMU library +# +# Copyright (C) 2015-2016 Red Hat Inc. +# Copyright (C) 2012 IBM Corp. +# +# Authors: +# Fam Zheng +# +# This work is licensed under the terms of the GNU GPL, version 2. See +# the COPYING file in the top-level directory. +# +# Based on qmp.py. +# + +import errno +import logging +import os +import subprocess +import re +import shutil +import socket +import tempfile + +from . import qmp + + +LOG = logging.getLogger(__name__) + +# Mapping host architecture to any additional architectures it can +# support which often includes its 32 bit cousin. +ADDITIONAL_ARCHES = { + "x86_64" : "i386", + "aarch64" : "armhf" +} + +def kvm_available(target_arch=None): + host_arch = os.uname()[4] + if target_arch and target_arch != host_arch: + if target_arch != ADDITIONAL_ARCHES.get(host_arch): + return False + return os.access("/dev/kvm", os.R_OK | os.W_OK) + + +class QEMUMachineError(Exception): + """ + Exception called when an error in QEMUMachine happens. + """ + + +class QEMUMachineAddDeviceError(QEMUMachineError): + """ + Exception raised when a request to add a device can not be fulfilled + + The failures are caused by limitations, lack of information or conflicting + requests on the QEMUMachine methods. This exception does not represent + failures reported by the QEMU binary itself. + """ + +class MonitorResponseError(qmp.QMPError): + """ + Represents erroneous QMP monitor reply + """ + def __init__(self, reply): + try: + desc = reply["error"]["desc"] + except KeyError: + desc = reply + super(MonitorResponseError, self).__init__(desc) + self.reply = reply + + +class QEMUMachine(object): + """ + A QEMU VM + + Use this object as a context manager to ensure the QEMU process terminates:: + + with VM(binary) as vm: + ... + # vm is guaranteed to be shut down here + """ + + def __init__(self, binary, args=None, wrapper=None, name=None, + test_dir="/var/tmp", monitor_address=None, + socket_scm_helper=None): + ''' + Initialize a QEMUMachine + + @param binary: path to the qemu binary + @param args: list of extra arguments + @param wrapper: list of arguments used as prefix to qemu binary + @param name: prefix for socket and log file names (default: qemu-PID) + @param test_dir: where to create socket and log file + @param monitor_address: address for QMP monitor + @param socket_scm_helper: helper program, required for send_fd_scm() + @note: Qemu process is not started until launch() is used. + ''' + if args is None: + args = [] + if wrapper is None: + wrapper = [] + if name is None: + name = "qemu-%d" % os.getpid() + self._name = name + self._monitor_address = monitor_address + self._vm_monitor = None + self._qemu_log_path = None + self._qemu_log_file = None + self._popen = None + self._binary = binary + self._args = list(args) # Force copy args in case we modify them + self._wrapper = wrapper + self._events = [] + self._iolog = None + self._socket_scm_helper = socket_scm_helper + self._qmp = None + self._qemu_full_args = None + self._test_dir = test_dir + self._temp_dir = None + self._launched = False + self._machine = None + self._console_set = False + self._console_device_type = None + self._console_address = None + self._console_socket = None + + # just in case logging wasn't configured by the main script: + logging.basicConfig(level=logging.DEBUG) + + def __enter__(self): + return self + + def __exit__(self, exc_type, exc_val, exc_tb): + self.shutdown() + return False + + # This can be used to add an unused monitor instance. + def add_monitor_null(self): + self._args.append('-monitor') + self._args.append('null') + + def add_fd(self, fd, fdset, opaque, opts=''): + """ + Pass a file descriptor to the VM + """ + options = ['fd=%d' % fd, + 'set=%d' % fdset, + 'opaque=%s' % opaque] + if opts: + options.append(opts) + + # This did not exist before 3.4, but since then it is + # mandatory for our purpose + if hasattr(os, 'set_inheritable'): + os.set_inheritable(fd, True) + + self._args.append('-add-fd') + self._args.append(','.join(options)) + return self + + # Exactly one of fd and file_path must be given. + # (If it is file_path, the helper will open that file and pass its + # own fd) + def send_fd_scm(self, fd=None, file_path=None): + # In iotest.py, the qmp should always use unix socket. + assert self._qmp.is_scm_available() + if self._socket_scm_helper is None: + raise QEMUMachineError("No path to socket_scm_helper set") + if not os.path.exists(self._socket_scm_helper): + raise QEMUMachineError("%s does not exist" % + self._socket_scm_helper) + + # This did not exist before 3.4, but since then it is + # mandatory for our purpose + if hasattr(os, 'set_inheritable'): + os.set_inheritable(self._qmp.get_sock_fd(), True) + if fd is not None: + os.set_inheritable(fd, True) + + fd_param = ["%s" % self._socket_scm_helper, + "%d" % self._qmp.get_sock_fd()] + + if file_path is not None: + assert fd is None + fd_param.append(file_path) + else: + assert fd is not None + fd_param.append(str(fd)) + + devnull = open(os.path.devnull, 'rb') + proc = subprocess.Popen(fd_param, stdin=devnull, stdout=subprocess.PIPE, + stderr=subprocess.STDOUT, close_fds=False) + output = proc.communicate()[0] + if output: + LOG.debug(output) + + return proc.returncode + + @staticmethod + def _remove_if_exists(path): + """ + Remove file object at path if it exists + """ + try: + os.remove(path) + except OSError as exception: + if exception.errno == errno.ENOENT: + return + raise + + def is_running(self): + return self._popen is not None and self._popen.poll() is None + + def exitcode(self): + if self._popen is None: + return None + return self._popen.poll() + + def get_pid(self): + if not self.is_running(): + return None + return self._popen.pid + + def _load_io_log(self): + if self._qemu_log_path is not None: + with open(self._qemu_log_path, "r") as iolog: + self._iolog = iolog.read() + + def _base_args(self): + if isinstance(self._monitor_address, tuple): + moncdev = "socket,id=mon,host=%s,port=%s" % ( + self._monitor_address[0], + self._monitor_address[1]) + else: + moncdev = 'socket,id=mon,path=%s' % self._vm_monitor + args = ['-chardev', moncdev, + '-mon', 'chardev=mon,mode=control', + '-display', 'none', '-vga', 'none'] + if self._machine is not None: + args.extend(['-machine', self._machine]) + if self._console_set: + self._console_address = os.path.join(self._temp_dir, + self._name + "-console.sock") + chardev = ('socket,id=console,path=%s,server,nowait' % + self._console_address) + args.extend(['-chardev', chardev]) + if self._console_device_type is None: + args.extend(['-serial', 'chardev:console']) + else: + device = '%s,chardev=console' % self._console_device_type + args.extend(['-device', device]) + return args + + def _pre_launch(self): + self._temp_dir = tempfile.mkdtemp(dir=self._test_dir) + if self._monitor_address is not None: + self._vm_monitor = self._monitor_address + else: + self._vm_monitor = os.path.join(self._temp_dir, + self._name + "-monitor.sock") + self._qemu_log_path = os.path.join(self._temp_dir, self._name + ".log") + self._qemu_log_file = open(self._qemu_log_path, 'wb') + + self._qmp = qmp.QEMUMonitorProtocol(self._vm_monitor, + server=True) + + def _post_launch(self): + self._qmp.accept() + + def _post_shutdown(self): + if self._qemu_log_file is not None: + self._qemu_log_file.close() + self._qemu_log_file = None + + self._qemu_log_path = None + + if self._console_socket is not None: + self._console_socket.close() + self._console_socket = None + + if self._temp_dir is not None: + shutil.rmtree(self._temp_dir) + self._temp_dir = None + + def launch(self): + """ + Launch the VM and make sure we cleanup and expose the + command line/output in case of exception + """ + + if self._launched: + raise QEMUMachineError('VM already launched') + + self._iolog = None + self._qemu_full_args = None + try: + self._launch() + self._launched = True + except: + self.shutdown() + + LOG.debug('Error launching VM') + if self._qemu_full_args: + LOG.debug('Command: %r', ' '.join(self._qemu_full_args)) + if self._iolog: + LOG.debug('Output: %r', self._iolog) + raise + + def _launch(self): + """ + Launch the VM and establish a QMP connection + """ + devnull = open(os.path.devnull, 'rb') + self._pre_launch() + self._qemu_full_args = (self._wrapper + [self._binary] + + self._base_args() + self._args) + LOG.debug('VM launch command: %r', ' '.join(self._qemu_full_args)) + self._popen = subprocess.Popen(self._qemu_full_args, + stdin=devnull, + stdout=self._qemu_log_file, + stderr=subprocess.STDOUT, + shell=False, + close_fds=False) + self._post_launch() + + def wait(self): + """ + Wait for the VM to power off + """ + self._popen.wait() + self._qmp.close() + self._load_io_log() + self._post_shutdown() + + def shutdown(self): + """ + Terminate the VM and clean up + """ + if self.is_running(): + try: + self._qmp.cmd('quit') + self._qmp.close() + except: + self._popen.kill() + self._popen.wait() + + self._load_io_log() + self._post_shutdown() + + exitcode = self.exitcode() + if exitcode is not None and exitcode < 0: + msg = 'qemu received signal %i: %s' + if self._qemu_full_args: + command = ' '.join(self._qemu_full_args) + else: + command = '' + LOG.warn(msg, -exitcode, command) + + self._launched = False + + def qmp(self, cmd, conv_keys=True, **args): + """ + Invoke a QMP command and return the response dict + """ + qmp_args = dict() + for key, value in args.items(): + if conv_keys: + qmp_args[key.replace('_', '-')] = value + else: + qmp_args[key] = value + + return self._qmp.cmd(cmd, args=qmp_args) + + def command(self, cmd, conv_keys=True, **args): + """ + Invoke a QMP command. + On success return the response dict. + On failure raise an exception. + """ + reply = self.qmp(cmd, conv_keys, **args) + if reply is None: + raise qmp.QMPError("Monitor is closed") + if "error" in reply: + raise MonitorResponseError(reply) + return reply["return"] + + def get_qmp_event(self, wait=False): + """ + Poll for one queued QMP events and return it + """ + if len(self._events) > 0: + return self._events.pop(0) + return self._qmp.pull_event(wait=wait) + + def get_qmp_events(self, wait=False): + """ + Poll for queued QMP events and return a list of dicts + """ + events = self._qmp.get_events(wait=wait) + events.extend(self._events) + del self._events[:] + self._qmp.clear_events() + return events + + @staticmethod + def event_match(event, match=None): + """ + Check if an event matches optional match criteria. + + The match criteria takes the form of a matching subdict. The event is + checked to be a superset of the subdict, recursively, with matching + values whenever the subdict values are not None. + + This has a limitation that you cannot explicitly check for None values. + + Examples, with the subdict queries on the left: + - None matches any object. + - {"foo": None} matches {"foo": {"bar": 1}} + - {"foo": None} matches {"foo": 5} + - {"foo": {"abc": None}} does not match {"foo": {"bar": 1}} + - {"foo": {"rab": 2}} matches {"foo": {"bar": 1, "rab": 2}} + """ + if match is None: + return True + + try: + for key in match: + if key in event: + if not QEMUMachine.event_match(event[key], match[key]): + return False + else: + return False + return True + except TypeError: + # either match or event wasn't iterable (not a dict) + return match == event + + def event_wait(self, name, timeout=60.0, match=None): + """ + event_wait waits for and returns a named event from QMP with a timeout. + + name: The event to wait for. + timeout: QEMUMonitorProtocol.pull_event timeout parameter. + match: Optional match criteria. See event_match for details. + """ + return self.events_wait([(name, match)], timeout) + + def events_wait(self, events, timeout=60.0): + """ + events_wait waits for and returns a named event from QMP with a timeout. + + events: a sequence of (name, match_criteria) tuples. + The match criteria are optional and may be None. + See event_match for details. + timeout: QEMUMonitorProtocol.pull_event timeout parameter. + """ + def _match(event): + for name, match in events: + if (event['event'] == name and + self.event_match(event, match)): + return True + return False + + # Search cached events + for event in self._events: + if _match(event): + self._events.remove(event) + return event + + # Poll for new events + while True: + event = self._qmp.pull_event(wait=timeout) + if _match(event): + return event + self._events.append(event) + + return None + + def get_log(self): + """ + After self.shutdown or failed qemu execution, this returns the output + of the qemu process. + """ + return self._iolog + + def add_args(self, *args): + """ + Adds to the list of extra arguments to be given to the QEMU binary + """ + self._args.extend(args) + + def set_machine(self, machine_type): + """ + Sets the machine type + + If set, the machine type will be added to the base arguments + of the resulting QEMU command line. + """ + self._machine = machine_type + + def set_console(self, device_type=None): + """ + Sets the device type for a console device + + If set, the console device and a backing character device will + be added to the base arguments of the resulting QEMU command + line. + + This is a convenience method that will either use the provided + device type, or default to a "-serial chardev:console" command + line argument. + + The actual setting of command line arguments will be be done at + machine launch time, as it depends on the temporary directory + to be created. + + @param device_type: the device type, such as "isa-serial". If + None is given (the default value) a "-serial + chardev:console" command line argument will + be used instead, resorting to the machine's + default device type. + """ + self._console_set = True + self._console_device_type = device_type + + @property + def console_socket(self): + """ + Returns a socket connected to the console + """ + if self._console_socket is None: + self._console_socket = socket.socket(socket.AF_UNIX, + socket.SOCK_STREAM) + self._console_socket.connect(self._console_address) + return self._console_socket diff --git a/qmp.py b/qmp/qmp.py similarity index 100% rename from qmp.py rename to qmp/qmp.py