Blame SOURCES/kvm-qemu-iotests-Test-job-with-block-jobs.patch

357786
From 5649220e08e99f848e013f1e0b432d6d2fc706fc Mon Sep 17 00:00:00 2001
357786
From: Kevin Wolf <kwolf@redhat.com>
357786
Date: Tue, 26 Jun 2018 09:48:36 +0200
357786
Subject: [PATCH 67/89] qemu-iotests: Test job-* with block jobs
357786
357786
RH-Author: Kevin Wolf <kwolf@redhat.com>
357786
Message-id: <20180626094856.6924-54-kwolf@redhat.com>
357786
Patchwork-id: 81101
357786
O-Subject: [RHV-7.6 qemu-kvm-rhev PATCH v2 53/73] qemu-iotests: Test job-* with block jobs
357786
Bugzilla: 1513543
357786
RH-Acked-by: Jeffrey Cody <jcody@redhat.com>
357786
RH-Acked-by: Max Reitz <mreitz@redhat.com>
357786
RH-Acked-by: Fam Zheng <famz@redhat.com>
357786
357786
This adds a test case that tests the new job-* QMP commands with
357786
mirror and backup block jobs.
357786
357786
Signed-off-by: Kevin Wolf <kwolf@redhat.com>
357786
(cherry picked from commit bdebdc712b06ba82e103d617c335830682cde242)
357786
Signed-off-by: Kevin Wolf <kwolf@redhat.com>
357786
Signed-off-by: Miroslav Rezanina <mrezanin@redhat.com>
357786
---
357786
 tests/qemu-iotests/219     | 209 +++++++++++++++++++++++++++++
357786
 tests/qemu-iotests/219.out | 327 +++++++++++++++++++++++++++++++++++++++++++++
357786
 tests/qemu-iotests/group   |   1 +
357786
 3 files changed, 537 insertions(+)
357786
 create mode 100755 tests/qemu-iotests/219
357786
 create mode 100644 tests/qemu-iotests/219.out
357786
357786
diff --git a/tests/qemu-iotests/219 b/tests/qemu-iotests/219
357786
new file mode 100755
357786
index 0000000..898a26e
357786
--- /dev/null
357786
+++ b/tests/qemu-iotests/219
357786
@@ -0,0 +1,209 @@
357786
+#!/usr/bin/env python
357786
+#
357786
+# Copyright (C) 2018 Red Hat, Inc.
357786
+#
357786
+# This program is free software; you can redistribute it and/or modify
357786
+# it under the terms of the GNU General Public License as published by
357786
+# the Free Software Foundation; either version 2 of the License, or
357786
+# (at your option) any later version.
357786
+#
357786
+# This program is distributed in the hope that it will be useful,
357786
+# but WITHOUT ANY WARRANTY; without even the implied warranty of
357786
+# MERCHANTABILITY or FITNESS FOR A PARTICULAR PURPOSE.  See the
357786
+# GNU General Public License for more details.
357786
+#
357786
+# You should have received a copy of the GNU General Public License
357786
+# along with this program.  If not, see <http://www.gnu.org/licenses/>.
357786
+#
357786
+# Creator/Owner: Kevin Wolf <kwolf@redhat.com>
357786
+#
357786
+# Check using the job-* QMP commands with block jobs
357786
+
357786
+import iotests
357786
+
357786
+iotests.verify_image_format(supported_fmts=['qcow2'])
357786
+
357786
+def pause_wait(vm, job_id):
357786
+    with iotests.Timeout(3, "Timeout waiting for job to pause"):
357786
+        while True:
357786
+            result = vm.qmp('query-jobs')
357786
+            for job in result['return']:
357786
+                if job['id'] == job_id and job['status'] in ['paused', 'standby']:
357786
+                    return job
357786
+
357786
+# Test that block-job-pause/resume and job-pause/resume can be mixed
357786
+def test_pause_resume(vm):
357786
+    for pause_cmd, pause_arg in [('block-job-pause', 'device'),
357786
+                                 ('job-pause', 'id')]:
357786
+        for resume_cmd, resume_arg in [('block-job-resume', 'device'),
357786
+                                       ('job-resume', 'id')]:
357786
+            iotests.log('=== Testing %s/%s ===' % (pause_cmd, resume_cmd))
357786
+
357786
+            iotests.log(vm.qmp(pause_cmd, **{pause_arg: 'job0'}))
357786
+            pause_wait(vm, 'job0')
357786
+            iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+            iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+            iotests.log(vm.qmp(resume_cmd, **{resume_arg: 'job0'}))
357786
+            iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+            iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+def test_job_lifecycle(vm, job, job_args, has_ready=False):
357786
+    iotests.log('')
357786
+    iotests.log('')
357786
+    iotests.log('Starting block job: %s (auto-finalize: %s; auto-dismiss: %s)' %
357786
+                (job,
357786
+                 job_args.get('auto-finalize', True),
357786
+                 job_args.get('auto-dismiss', True)))
357786
+    iotests.log(vm.qmp(job, job_id='job0', **job_args))
357786
+
357786
+    # Depending on the storage, the first request may or may not have completed
357786
+    # yet, so filter out the progress. Later query-job calls don't need the
357786
+    # filtering because the progress is made deterministic by the block job
357786
+    # speed
357786
+    result = vm.qmp('query-jobs')
357786
+    for j in result['return']:
357786
+        del j['current-progress']
357786
+    iotests.log(result)
357786
+
357786
+    # undefined -> created -> running
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+
357786
+    # RUNNING state:
357786
+    # pause/resume should work, complete/finalize/dismiss should error out
357786
+    iotests.log('')
357786
+    iotests.log('Pause/resume in RUNNING')
357786
+    test_pause_resume(vm)
357786
+
357786
+    iotests.log(vm.qmp('job-complete', id='job0'))
357786
+    iotests.log(vm.qmp('job-finalize', id='job0'))
357786
+    iotests.log(vm.qmp('job-dismiss', id='job0'))
357786
+
357786
+    iotests.log(vm.qmp('block-job-complete', device='job0'))
357786
+    iotests.log(vm.qmp('block-job-finalize', id='job0'))
357786
+    iotests.log(vm.qmp('block-job-dismiss', id='job0'))
357786
+
357786
+    # Let the job complete (or transition to READY if it supports that)
357786
+    iotests.log(vm.qmp('block-job-set-speed', device='job0', speed=0))
357786
+    if has_ready:
357786
+        iotests.log('')
357786
+        iotests.log('Waiting for READY state...')
357786
+        vm.event_wait('BLOCK_JOB_READY')
357786
+        iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+        iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+        # READY state:
357786
+        # pause/resume/complete should work, finalize/dismiss should error out
357786
+        iotests.log('')
357786
+        iotests.log('Pause/resume in READY')
357786
+        test_pause_resume(vm)
357786
+
357786
+        iotests.log(vm.qmp('job-finalize', id='job0'))
357786
+        iotests.log(vm.qmp('job-dismiss', id='job0'))
357786
+
357786
+        iotests.log(vm.qmp('block-job-finalize', id='job0'))
357786
+        iotests.log(vm.qmp('block-job-dismiss', id='job0'))
357786
+
357786
+        # Transition to WAITING
357786
+        iotests.log(vm.qmp('job-complete', id='job0'))
357786
+
357786
+    # Move to WAITING and PENDING state
357786
+    iotests.log('')
357786
+    iotests.log('Waiting for PENDING state...')
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+
357786
+    if not job_args.get('auto-finalize', True):
357786
+        # PENDING state:
357786
+        # finalize should work, pause/complete/dismiss should error out
357786
+        iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+        iotests.log(vm.qmp('job-pause', id='job0'))
357786
+        iotests.log(vm.qmp('job-complete', id='job0'))
357786
+        iotests.log(vm.qmp('job-dismiss', id='job0'))
357786
+
357786
+        iotests.log(vm.qmp('block-job-pause', device='job0'))
357786
+        iotests.log(vm.qmp('block-job-complete', device='job0'))
357786
+        iotests.log(vm.qmp('block-job-dismiss', id='job0'))
357786
+
357786
+        # Transition to CONCLUDED
357786
+        iotests.log(vm.qmp('job-finalize', id='job0'))
357786
+
357786
+
357786
+    # Move to CONCLUDED state
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+
357786
+    if not job_args.get('auto-dismiss', True):
357786
+        # CONCLUDED state:
357786
+        # dismiss should work, pause/complete/finalize should error out
357786
+        iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+        iotests.log(vm.qmp('job-pause', id='job0'))
357786
+        iotests.log(vm.qmp('job-complete', id='job0'))
357786
+        iotests.log(vm.qmp('job-finalize', id='job0'))
357786
+
357786
+        iotests.log(vm.qmp('block-job-pause', device='job0'))
357786
+        iotests.log(vm.qmp('block-job-complete', device='job0'))
357786
+        iotests.log(vm.qmp('block-job-finalize', id='job0'))
357786
+
357786
+        # Transition to NULL
357786
+        iotests.log(vm.qmp('job-dismiss', id='job0'))
357786
+
357786
+    # Move to NULL state
357786
+    iotests.log(iotests.filter_qmp_event(vm.event_wait('JOB_STATUS_CHANGE')))
357786
+    iotests.log(vm.qmp('query-jobs'))
357786
+
357786
+
357786
+with iotests.FilePath('disk.img') as disk_path, \
357786
+     iotests.FilePath('copy.img') as copy_path, \
357786
+     iotests.VM() as vm:
357786
+
357786
+    img_size = '4M'
357786
+    iotests.qemu_img_create('-f', iotests.imgfmt, disk_path, img_size)
357786
+    iotests.qemu_io('-c', 'write 0 %s' % (img_size),
357786
+                    '-f', iotests.imgfmt, disk_path)
357786
+
357786
+    iotests.log('Launching VM...')
357786
+    vm.add_blockdev(vm.qmp_to_opts({
357786
+        'driver': iotests.imgfmt,
357786
+        'node-name': 'drive0-node',
357786
+        'file': {
357786
+            'driver': 'file',
357786
+            'filename': disk_path,
357786
+        },
357786
+    }))
357786
+    vm.launch()
357786
+
357786
+    # In order to keep things deterministic (especially progress in query-job,
357786
+    # but related to this also automatic state transitions like job
357786
+    # completion), but still get pause points often enough to avoid making this
357786
+    # test very slow, it's important to have the right ratio between speed and
357786
+    # buf_size.
357786
+    #
357786
+    # For backup, buf_size is hard-coded to the source image cluster size (64k),
357786
+    # so we'll pick the same for mirror. The slice time, i.e. the granularity
357786
+    # of the rate limiting is 100ms. With a speed of 256k per second, we can
357786
+    # get four pause points per second. This gives us 250ms per iteration,
357786
+    # which should be enough to stay deterministic.
357786
+
357786
+    test_job_lifecycle(vm, 'drive-mirror', has_ready=True, job_args={
357786
+        'device': 'drive0-node',
357786
+        'target': copy_path,
357786
+        'sync': 'full',
357786
+        'speed': 262144,
357786
+        'buf_size': 65536,
357786
+    })
357786
+
357786
+    for auto_finalize in [True, False]:
357786
+        for auto_dismiss in [True, False]:
357786
+            test_job_lifecycle(vm, 'drive-backup', job_args={
357786
+                'device': 'drive0-node',
357786
+                'target': copy_path,
357786
+                'sync': 'full',
357786
+                'speed': 262144,
357786
+                'auto-finalize': auto_finalize,
357786
+                'auto-dismiss': auto_dismiss,
357786
+            })
357786
+
357786
+    vm.shutdown()
357786
diff --git a/tests/qemu-iotests/219.out b/tests/qemu-iotests/219.out
357786
new file mode 100644
357786
index 0000000..346801b
357786
--- /dev/null
357786
+++ b/tests/qemu-iotests/219.out
357786
@@ -0,0 +1,327 @@
357786
+Launching VM...
357786
+
357786
+
357786
+Starting block job: drive-mirror (auto-finalize: True; auto-dismiss: True)
357786
+{u'return': {}}
357786
+{u'return': [{u'status': u'running', u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'created', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+
357786
+Pause/resume in RUNNING
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 65536, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 327680, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for READY state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'ready', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'ready', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+
357786
+Pause/resume in READY
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'standby', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'standby', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'ready', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'ready', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'standby', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'standby', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'ready', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'ready', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'standby', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'standby', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'ready', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'ready', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'standby', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'standby', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'ready', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'ready', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'mirror'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'ready' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'ready' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'ready' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'ready' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for PENDING state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'waiting', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'pending', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'concluded', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'null', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': []}
357786
+
357786
+
357786
+Starting block job: drive-backup (auto-finalize: True; auto-dismiss: True)
357786
+{u'return': {}}
357786
+{u'return': [{u'status': u'running', u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'created', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+
357786
+Pause/resume in RUNNING
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 65536, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 327680, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for PENDING state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'waiting', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'pending', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'concluded', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'null', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': []}
357786
+
357786
+
357786
+Starting block job: drive-backup (auto-finalize: True; auto-dismiss: False)
357786
+{u'return': {}}
357786
+{u'return': [{u'status': u'running', u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'created', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+
357786
+Pause/resume in RUNNING
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 65536, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 327680, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for PENDING state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'waiting', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'pending', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'concluded', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'concluded', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'finalize'"}}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'null', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': []}
357786
+
357786
+
357786
+Starting block job: drive-backup (auto-finalize: False; auto-dismiss: True)
357786
+{u'return': {}}
357786
+{u'return': [{u'status': u'running', u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'created', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+
357786
+Pause/resume in RUNNING
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 65536, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 327680, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for PENDING state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'waiting', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'pending', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'pending', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'concluded', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'null', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': []}
357786
+
357786
+
357786
+Starting block job: drive-backup (auto-finalize: False; auto-dismiss: False)
357786
+{u'return': {}}
357786
+{u'return': [{u'status': u'running', u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'created', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+
357786
+Pause/resume in RUNNING
357786
+=== Testing block-job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 65536, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing block-job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 131072, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/block-job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 196608, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+=== Testing job-pause/job-resume ===
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'paused', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'paused', u'current-progress': 262144, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'running', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'running', u'current-progress': 327680, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'running' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+
357786
+Waiting for PENDING state...
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'waiting', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'pending', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'pending', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'dismiss'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'pending' cannot accept command verb 'dismiss'"}}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'concluded', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': [{u'status': u'concluded', u'current-progress': 4194304, u'total-progress': 4194304, u'id': u'job0', u'type': u'backup'}]}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'finalize'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'pause'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'complete'"}}
357786
+{u'error': {u'class': u'GenericError', u'desc': u"Job 'job0' in state 'concluded' cannot accept command verb 'finalize'"}}
357786
+{u'return': {}}
357786
+{u'timestamp': {u'seconds': 'SECS', u'microseconds': 'USECS'}, u'data': {u'status': u'null', u'id': u'job0'}, u'event': u'JOB_STATUS_CHANGE'}
357786
+{u'return': []}
357786
diff --git a/tests/qemu-iotests/group b/tests/qemu-iotests/group
357786
index cd7bc29..5c55adc 100644
357786
--- a/tests/qemu-iotests/group
357786
+++ b/tests/qemu-iotests/group
357786
@@ -217,3 +217,4 @@
357786
 216 rw auto quick
357786
 217 rw auto quick
357786
 218 rw auto quick
357786
+219 rw auto
357786
-- 
357786
1.8.3.1
357786