Details
-
Bug
-
Status: Open
-
Major
-
Resolution: Unresolved
-
4.5.1
-
None
-
Security Level: Public (Anyone can view this level - this is the default.)
-
None
-
MS 4.5.1 RC + Xenserver 6.5
Description
2015-05-07 01:08:02,833 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Run VM work job: com.cloud.vm.VmWorkAttachVolume for VM 8, job origin: 55
2015-05-07 01:08:02,834 DEBUG [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Execute VM work job: com.cloud.vm.VmWorkAttachVolume
2015-05-07 01:08:02,862 DEBUG [c.c.a.t.Request] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Seq 1-5997950278727369144: Sending { Cmd , MgmtId: 345043735628, via: 1(xen651), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"e499088f-88be-4a17-a7a8-807ce56fa58b","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e007c270-2b1b-3ce9-ae92-a98b94eef7eb","id":1,"poolType":"NetworkFilesystem","host":"192.168.217.11","path":"/exports/prim1","port":2049,"url":"NetworkFilesystem://192.168.217.11/exports/prim1/?ROLE=Primary&STOREUUID=e007c270-2b1b-3ce9-ae92-a98b94eef7eb"}},"name":"mach_vol","size":52428800,"path":"6f780380-c4bb-407e-9014-47f8cbf9e36e","volumeId":8,"accountId":2,"format":"VHD","provisioningType":"THIN","id":8,"hypervisorType":"XenServer"}},"diskSeq":1,"path":"6f780380-c4bb-407e-9014-47f8cbf9e36e","type":"DATADISK","_details":{"managed":"false","storagePort":"2049","storageHost":"192.168.217.11","volumeSize":"52428800"}},"vmName":"i-2-8-VM","inSeq":false,"wait":0}}] }
2015-05-07 01:08:02,862 DEBUG [c.c.a.t.Request] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Seq 1-5997950278727369144: Executing: { Cmd , MgmtId: 345043735628, via: 1(xen651), Ver: v1, Flags: 100011, [{"org.apache.cloudstack.storage.command.AttachCommand":{"disk":{"data":{"org.apache.cloudstack.storage.to.VolumeObjectTO":{"uuid":"e499088f-88be-4a17-a7a8-807ce56fa58b","volumeType":"DATADISK","dataStore":{"org.apache.cloudstack.storage.to.PrimaryDataStoreTO":{"uuid":"e007c270-2b1b-3ce9-ae92-a98b94eef7eb","id":1,"poolType":"NetworkFilesystem","host":"192.168.217.11","path":"/exports/prim1","port":2049,"url":"NetworkFilesystem://192.168.217.11/exports/prim1/?ROLE=Primary&STOREUUID=e007c270-2b1b-3ce9-ae92-a98b94eef7eb"}},"name":"mach_vol","size":52428800,"path":"6f780380-c4bb-407e-9014-47f8cbf9e36e","volumeId":8,"accountId":2,"format":"VHD","provisioningType":"THIN","id":8,"hypervisorType":"XenServer"}},"diskSeq":1,"path":"6f780380-c4bb-407e-9014-47f8cbf9e36e","type":"DATADISK","_details":{"managed":"false","storagePort":"2049","storageHost":"192.168.217.11","volumeSize":"52428800"}},"vmName":"i-2-8-VM","inSeq":false,"wait":0}}] }
2015-05-07 01:08:02,863 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-343:ctx-68ff6403) Seq 1-5997950278727369144: Executing request
2015-05-07 01:08:02,913 WARN [c.c.h.x.r.XenServerStorageProcessor] (DirectAgent-343:ctx-68ff6403) : You attempted an operation on a VM which requires PV drivers to be installed but the drivers were not detected
2015-05-07 01:08:02,913 DEBUG [c.c.a.m.DirectAgentAttache] (DirectAgent-343:ctx-68ff6403) Seq 1-5997950278727369144: Response Received:
2015-05-07 01:08:02,913 DEBUG [c.c.a.t.Request] (DirectAgent-343:ctx-68ff6403) Seq 1-5997950278727369144: Processing: { Ans: , MgmtId: 345043735628, via: 1, Ver: v1, Flags: 10, [{"org.apache.cloudstack.storage.command.AttachAnswer":{"result":false,"details":"You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.","wait":0}}] }
2015-05-07 01:08:02,913 DEBUG [c.c.a.t.Request] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Seq 1-5997950278727369144: Received: { Ans: , MgmtId: 345043735628, via: 1, Ver: v1, Flags: 10,
}
2015-05-07 01:08:02,915 ERROR [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Invocation exception, caused by: com.cloud.utils.exception.CloudRuntimeException: Failed to attach volume mach_vol to VM VM-2eb573b5-1064-43dd-b48e-f68bff5eef0b; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.
2015-05-07 01:08:02,915 INFO [c.c.v.VmWorkJobHandlerProxy] (Work-Job-Executor-15:ctx-23658efb job-55/job-56 ctx-95549691) Rethrow exception com.cloud.utils.exception.CloudRuntimeException: Failed to attach volume mach_vol to VM VM-2eb573b5-1064-43dd-b48e-f68bff5eef0b; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.
2015-05-07 01:08:02,915 DEBUG [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Done with run of VM work job: com.cloud.vm.VmWorkAttachVolume for VM 8, job origin: 55
2015-05-07 01:08:02,916 ERROR [c.c.v.VmWorkJobDispatcher] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Unable to complete AsyncJobVO
, job origin:55
com.cloud.utils.exception.CloudRuntimeException: Failed to attach volume mach_vol to VM VM-2eb573b5-1064-43dd-b48e-f68bff5eef0b; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.
at com.cloud.storage.VolumeApiServiceImpl.sendAttachVolumeCommand(VolumeApiServiceImpl.java:2368)
at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1238)
at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2710)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2749)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)
at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:183)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:150)
at org.springframework.aop.interceptor.ExposeInvocationInterceptor.invoke(ExposeInvocationInterceptor.java:91)
at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:172)
at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
at com.sun.proxy.$Proxy190.handleVmWorkJob(Unknown Source)
at com.cloud.vm.VmWorkJobDispatcher.runJob(VmWorkJobDispatcher.java:103)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.runInContext(AsyncJobManagerImpl.java:537)
at org.apache.cloudstack.managed.context.ManagedContextRunnable$1.run(ManagedContextRunnable.java:49)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext$1.call(DefaultManagedContext.java:56)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.callWithContext(DefaultManagedContext.java:103)
at org.apache.cloudstack.managed.context.impl.DefaultManagedContext.runWithContext(DefaultManagedContext.java:53)
at org.apache.cloudstack.managed.context.ManagedContextRunnable.run(ManagedContextRunnable.java:46)
at org.apache.cloudstack.framework.jobs.impl.AsyncJobManagerImpl$5.run(AsyncJobManagerImpl.java:494)
at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471)
at java.util.concurrent.FutureTask.run(FutureTask.java:262)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615)
at java.lang.Thread.run(Thread.java:745)
2015-05-07 01:08:02,922 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Complete async job-56, jobStatus: FAILED, resultCode: 0, result: rO0ABXNyAC9jb20uY2xvdWQudXRpbHMuZXhjZXB0aW9uLkNsb3VkUnVudGltZUV4Y2VwdGlvbgAAAABWTU9yAwABSQALY3NFcnJvckNvZGV4cgAaamF2YS5sYW5nLlJ1bnRpbWVFeGNlcHRpb26eXwZHCjSD5QIAAHhyABNqYXZhLmxhbmcuRXhjZXB0aW9u0P0fPho7HMQCAAB4cgATamF2YS5sYW5nLlRocm93YWJsZdXGNSc5d7jLAwAETAAFY2F1c2V0ABVMamF2YS9sYW5nL1Rocm93YWJsZTtMAA1kZXRhaWxNZXNzYWdldAASTGphdmEvbGFuZy9TdHJpbmc7WwAKc3RhY2tUcmFjZXQAHltMamF2YS9sYW5nL1N0YWNrVHJhY2VFbGVtZW50O0wAFHN1cHByZXNzZWRFeGNlcHRpb25zdAAQTGphdmEvdXRpbC9MaXN0O3hwcQB-AAh0AM9GYWlsZWQgdG8gYXR0YWNoIHZvbHVtZSBtYWNoX3ZvbCB0byBWTSBWTS0yZWI1NzNiNS0xMDY0LTQzZGQtYjQ4ZS1mNjhiZmY1ZWVmMGI7IFlvdSBhdHRlbXB0ZWQgYW4gb3BlcmF0aW9uIHRoYXQgcmVxdWlyZXMgUFYgZHJpdmVycyB0byBiZSBpbnN0YWxsZWQgb24gdGhlIFZNLiBQbGVhc2UgaW5zdGFsbCB0aGVtIGJ5IGluc2VydGluZyB4ZW4tcHYtZHJ2Lmlzby51cgAeW0xqYXZhLmxhbmcuU3RhY2tUcmFjZUVsZW1lbnQ7AkYqPDz9IjkCAAB4cAAAACFzcgAbamF2YS5sYW5nLlN0YWNrVHJhY2VFbGVtZW50YQnFmiY23YUCAARJAApsaW5lTnVtYmVyTAAOZGVjbGFyaW5nQ2xhc3NxAH4ABUwACGZpbGVOYW1lcQB-AAVMAAptZXRob2ROYW1lcQB-AAV4cAAACUB0ACZjb20uY2xvdWQuc3RvcmFnZS5Wb2x1bWVBcGlTZXJ2aWNlSW1wbHQAGVZvbHVtZUFwaVNlcnZpY2VJbXBsLmphdmF0ABdzZW5kQXR0YWNoVm9sdW1lQ29tbWFuZHNxAH4ADAAABNZxAH4ADnEAfgAPdAAbb3JjaGVzdHJhdGVBdHRhY2hWb2x1bWVUb1ZNc3EAfgAMAAAKlnEAfgAOcQB-AA9xAH4AEnNxAH4ADP___50ACRzdW4ucmVmbGVjdC5OYXRpdmVNZXRob2RBY2Nlc3NvckltcGx0AB1OYXRpdmVNZXRob2RBY2Nlc3NvckltcGwuamF2YXQAB2ludm9rZTBzcQB-AAwAAAA5cQB-ABVxAH4AFnQABmludm9rZXNxAH4ADAAAACt0AChzdW4ucmVmbGVjdC5EZWxlZ2F0aW5nTWV0aG9kQWNjZXNzb3JJbXBsdAAhRGVsZWdhdGluZ01ldGhvZEFjY2Vzc29ySW1wbC5qYXZhcQB-ABlzcQB-AAwAAAJedAAYamF2YS5sYW5nLnJlZmxlY3QuTWV0aG9kdAALTWV0aG9kLmphdmFxAH4AGXNxAH4ADAAAAGt0ACJjb20uY2xvdWQudm0uVm1Xb3JrSm9iSGFuZGxlclByb3h5dAAaVm1Xb3JrSm9iSGFuZGxlclByb3h5LmphdmF0AA9oYW5kbGVWbVdvcmtKb2JzcQB-AAwAAAq9cQB-AA5xAH4AD3EAfgAjc3EAfgAM___nEAfgAVcQB-ABZxAH4AF3NxAH4ADAAAADlxAH4AFXEAfgAWcQB-ABlzcQB-AAwAAAArcQB-ABtxAH4AHHEAfgAZc3EAfgAMAAACXnEAfgAecQB-AB9xAH4AGXNxAH4ADAAAAT10AChvcmcuc3ByaW5nZnJhbWV3b3JrLmFvcC5zdXBwb3J0LkFvcFV0aWxzdAANQW9wVXRpbHMuamF2YXQAHmludm9rZUpvaW5wb2ludFVzaW5nUmVmbGVjdGlvbnNxAH4ADAAAALd0ADxvcmcuc3ByaW5nZnJhbWV3b3JrLmFvcC5mcmFtZXdvcmsuUmVmbGVjdGl2ZU1ldGhvZEludm9jYXRpb250AB9SZWZsZWN0aXZlTWV0aG9kSW52b2NhdGlvbi5qYXZhdAAPaW52b2tlSm9pbnBvaW50c3EAfgAMAAAAlnEAfgAucQB-AC90AAdwcm9jZWVkc3EAfgAMAAAAW3QAP29yZy5zcHJpbmdmcmFtZXdvcmsuYW9wLmludGVyY2VwdG9yLkV4cG9zZUludm9jYXRpb25JbnRlcmNlcHRvcnQAIEV4cG9zZUludm9jYXRpb25JbnRlcmNlcHRvci5qYXZhcQB-ABlzcQB-AAwAAACscQB-AC5xAH4AL3EAfgAyc3EAfgAMAAAAzHQANG9yZy5zcHJpbmdmcmFtZXdvcmsuYW9wLmZyYW1ld29yay5KZGtEeW5hbWljQW9wUHJveHl0ABdKZGtEeW5hbWljQW9wUHJveHkuamF2YXEAfgAZc3EAfgAM____3QAF2NvbS5zdW4ucHJveHkuJFByb3h5MTkwcHEAfgAjc3EAfgAMAAAAZ3QAIGNvbS5jbG91ZC52bS5WbVdvcmtKb2JEaXNwYXRjaGVydAAYVm1Xb3JrSm9iRGlzcGF0Y2hlci5qYXZhdAAGcnVuSm9ic3EAfgAMAAACGXQAP29yZy5hcGFjaGUuY2xvdWRzdGFjay5mcmFtZXdvcmsuam9icy5pbXBsLkFzeW5jSm9iTWFuYWdlckltcGwkNXQAGEFzeW5jSm9iTWFuYWdlckltcGwuamF2YXQADHJ1bkluQ29udGV4dHNxAH4ADAAAADF0AD5vcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0Lk1hbmFnZWRDb250ZXh0UnVubmFibGUkMXQAG01hbmFnZWRDb250ZXh0UnVubmFibGUuamF2YXQAA3J1bnNxAH4ADAAAADh0AEJvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0LmltcGwuRGVmYXVsdE1hbmFnZWRDb250ZXh0JDF0ABpEZWZhdWx0TWFuYWdlZENvbnRleHQuamF2YXQABGNhbGxzcQB-AAwAAABndABAb3JnLmFwYWNoZS5jbG91ZHN0YWNrLm1hbmFnZWQuY29udGV4dC5pbXBsLkRlZmF1bHRNYW5hZ2VkQ29udGV4dHEAfgBKdAAPY2FsbFdpdGhDb250ZXh0c3EAfgAMAAAANXEAfgBNcQB-AEp0AA5ydW5XaXRoQ29udGV4dHNxAH4ADAAAAC50ADxvcmcuYXBhY2hlLmNsb3Vkc3RhY2subWFuYWdlZC5jb250ZXh0Lk1hbmFnZWRDb250ZXh0UnVubmFibGVxAH4ARnEAfgBHc3EAfgAMAAAB7nEAfgBBcQB-AEJxAH4AR3NxAH4ADAAAAdd0AC5qYXZhLnV0aWwuY29uY3VycmVudC5FeGVjdXRvcnMkUnVubmFibGVBZGFwdGVydAAORXhlY3V0b3JzLmphdmFxAH4AS3NxAH4ADAAAAQZ0AB9qYXZhLnV0aWwuY29uY3VycmVudC5GdXR1cmVUYXNrdAAPRnV0dXJlVGFzay5qYXZhcQB-AEdzcQB-AAwAAAR5dAAnamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9ydAAXVGhyZWFkUG9vbEV4ZWN1dG9yLmphdmF0AAlydW5Xb3JrZXJzcQB-AAwAAAJndAAuamF2YS51dGlsLmNvbmN1cnJlbnQuVGhyZWFkUG9vbEV4ZWN1dG9yJFdvcmtlcnEAfgBccQB-AEdzcQB-AAwAAALpdAAQamF2YS5sYW5nLlRocmVhZHQAC1RocmVhZC5qYXZhcQB-AEdzcgAmamF2YS51dGlsLkNvbGxlY3Rpb25zJFVubW9kaWZpYWJsZUxpc3T8DyUxteyOEAIAAUwABGxpc3RxAH4AB3hyACxqYXZhLnV0aWwuQ29sbGVjdGlvbnMkVW5tb2RpZmlhYmxlQ29sbGVjdGlvbhlCAIDLXvceAgABTAABY3QAFkxqYXZhL3V0aWwvQ29sbGVjdGlvbjt4cHNyABNqYXZhLnV0aWwuQXJyYXlMaXN0eIHSHZnHYZ0DAAFJAARzaXpleHAAAAAAdwQAAAAAeHEAfgBoeAAAEJp3CAAAAAAAAAAAeA
2015-05-07 01:08:02,923 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Publish async job-56 complete on message bus
2015-05-07 01:08:02,923 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Wake up jobs related to job-56
2015-05-07 01:08:02,923 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Update db status for job-56
2015-05-07 01:08:02,924 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Wake up jobs joined with job-56 and disjoin all subjobs created from job- 56
2015-05-07 01:08:02,928 DEBUG [o.a.c.f.j.i.AsyncJobManagerImpl] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Done executing com.cloud.vm.VmWorkAttachVolume for job-56
2015-05-07 01:08:02,929 INFO [o.a.c.f.j.i.AsyncJobMonitor] (Work-Job-Executor-15:ctx-23658efb job-55/job-56) Remove job-56 from job monitoring
2015-05-07 01:08:02,934 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-7:null) SeqA 3-1506: Processing Seq 3-1506: { Cmd , MgmtId: -1, via: 3, Ver: v1, Flags: 11, [{"com.cloud.agent.api.ConsoleProxyLoadReportCommand":{"_proxyVmId":2,"_loadInfo":"{\n \"connections\": [\n
\n ]\n}","wait":0}}] }
2015-05-07 01:08:02,936 DEBUG [c.c.a.m.AgentManagerImpl] (AgentManager-Handler-7:null) SeqA 3-1506: Sending Seq 3-1506: { Ans: , MgmtId: 345043735628, via: 3, Ver: v1, Flags: 100010, [{"com.cloud.agent.api.AgentControlAnswer":{"result":true,"wait":0}}] }
2015-05-07 01:08:02,937 ERROR [c.c.a.ApiAsyncJobDispatcher] (API-Job-Executor-35:ctx-d37f8eea job-55) Unexpected exception while executing org.apache.cloudstack.api.command.admin.volume.AttachVolumeCmdByAdmin
com.cloud.utils.exception.CloudRuntimeException: Failed to attach volume mach_vol to VM VM-2eb573b5-1064-43dd-b48e-f68bff5eef0b; You attempted an operation that requires PV drivers to be installed on the VM. Please install them by inserting xen-pv-drv.iso.
at com.cloud.storage.VolumeApiServiceImpl.sendAttachVolumeCommand(VolumeApiServiceImpl.java:2368)
at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:1238)
at com.cloud.storage.VolumeApiServiceImpl.orchestrateAttachVolumeToVM(VolumeApiServiceImpl.java:2710)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.cloud.vm.VmWorkJobHandlerProxy.handleVmWorkJob(VmWorkJobHandlerProxy.java:107)
at com.cloud.storage.VolumeApiServiceImpl.handleVmWorkJob(VolumeApiServiceImpl.java:2749)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:317)