Suresh Kumar Anaparti created CLOUDSTACK-8858: -------------------------------------------------
Summary: listVolumes API fails for a particular domain with NPE Key: CLOUDSTACK-8858 URL: https://issues.apache.org/jira/browse/CLOUDSTACK-8858 Project: CloudStack Issue Type: Bug Security Level: Public (Anyone can view this level - this is the default.) Reporter: Suresh Kumar Anaparti Fix For: 4.6.0 ISSUE ================== listVolumes API fails for a particular domain with NPE Using Firebug - API call from UI and return info curl 'https://ccp.mysecurecloud.com/client/api?command=listVolumes&response=json&sessionkey=IloZwsbwujqsU7D3PCIpNNYKFhw%3D&zoneid=6ab6c1a8-6ddd-4dfc-af0d-e3173ae248f2&domainid=0d00ce3f-c4b4-4a1e-83f9-a7d3cc33a0d7&listAll=true&page=1&pagesize=20&_=1420556952860' -H 'Host: ccp.mysecurecloud.com' -H 'User-Agent: Mozilla/5.0 (Windows NT 6.1; WOW64; rv:34.0) Gecko/20100101 Firefox/34.0' -H 'Accept: application/json, text/javascript, */*; q=0.01' -H 'Accept-Language: en-US,en;q=0.5' -H 'Accept-Encoding: gzip, deflate' -H 'X-Requested-With: XMLHttpRequest' -H 'Referer: https://ccp.mysecurecloud.com/client/' -H 'Cookie: sessionKey=IloZwsbwujqsU7D3PCIpNNYKFhw%253D; username=jrapine; account=jrapine; domainid=1; role=1; userfullname=Jim%20Rapine; userid=396262ae-3412-4186-ad32-a6ed18103f79; capabilities=%5Bobject%20Object%5D; supportELB=false; kvmsnapshotenabled=false; regionsecondaryenabled=false; userpublictemplateenabled=true; userProjectsEnabled=true; JSESSIONID=F8EFA3C64B98928379FFEDCBC6E32DEB; NSC_DQ_Qsjwbuf=ffffffff09671c1045525d5f4f58455e445a4a4229a0' Params _ 1420556952860 command listVolumes domainid 0d00ce3f-c4b4-4a1e-83f9-a7d3cc33a0d7 listAll true page 1 pagesize 20 response json sessionkey IloZwsbwujqsU7D3PCIpNNYKFhw= zoneid 6ab6c1a8-6ddd-4dfc-af0d-e3173ae248f2 Response { "listvolumesresponse" : {"uuidList":[],"errorcode":530,"cserrorcode":9999} } TRACE logging on the management server and got the following trace of the API call Log trace of API call with TRACE logging enabled 2015-01-06 10:24:42,266 DEBUG [c.c.a.ApiServlet] (catalina-exec-21:ctx-76704af2) ===START=== 10.111.1.3 -- GET command=listVolumes&response=json&sessionkey=PfDwFth3hoqeE1cgIUkfBWRHpgo%3D&zoneid=6ab6c1a8-6ddd-4dfc-af0d-e3173ae248f2&domainid=0d00ce3f-c4b4-4a1e-83f9-a7d3cc33a0d7&listAll=true&page=1&pagesize=20&_=1420557899743 2015-01-06 10:24:42,266 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,267 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2) Creating a DB connection with no txn: for 0: dbconn245854895. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.findByIdIncludingRemoved:946-GeneratedMethodAccessor122.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33-ReflectiveMethodInvocation.proceed:161 2015-01-06 10:24:42,267 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2) Preparing: SELECT user.id, user.username, user.password, user.firstname, user.lastname, user.account_id, user.email, user.state, user.api_key, user.secret_key, user.created, user.removed, user.timezone, user.registration_token, user.is_registered, user.uuid, user.default FROM user WHERE user.id = ? 2015-01-06 10:24:42,271 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2) Closing: com.mysql.jdbc.JDBC4PreparedStatement@135a46ad: SELECT user.id, user.username, user.password, user.firstname, user.lastname, user.account_id, user.email, user.state, user.api_key, user.secret_key, user.created, user.removed, user.timezone, user.registration_token, user.is_registered, user.uuid, user.default FROM user WHERE user.id = 46 2015-01-06 10:24:42,271 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2) Closing DB connection: dbconn245854895 2015-01-06 10:24:42,271 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2) Creating a DB connection with no txn: for 0: dbconn385962477. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.findByIdIncludingRemoved:946-GeneratedMethodAccessor122.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33-ReflectiveMethodInvocation.proceed:161 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2) Preparing: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = ? 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2) Closing: com.mysql.jdbc.PreparedStatement@57a9aa67: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = 45 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2) Closing DB connection: dbconn385962477 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,273 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,274 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,274 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1296344456. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.findByIdIncludingRemoved:946-GeneratedMethodAccessor122.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33-ReflectiveMethodInvocation.proceed:161 2015-01-06 10:24:42,274 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = ? 2015-01-06 10:24:42,274 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@55cfab59: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = 45 2015-01-06 10:24:42,275 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1296344456 2015-01-06 10:24:42,275 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,276 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn889695595. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-GenericDaoBase.findByUuidIncludingRemoved:941-EntityManagerImpl.findByUuidIncludingRemoved:64-GeneratedMethodAccessor260.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,276 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT data_center.id, data_center.name, data_center.description, data_center.dns1, data_center.dns2, data_center.ip6_dns1, data_center.ip6_dns2, data_center.internal_dns1, data_center.internal_dns2, data_center.router_mac_address, data_center.guest_network_cidr, data_center.domain_id, data_center.domain, data_center.networktype, data_center.dns_provider, data_center.dhcp_provider, data_center.gateway_provider, data_center.vpn_provider, data_center.userdata_provider, data_center.lb_provider, data_center.firewall_provider, data_center.mac_address, data_center.zone_token, data_center.removed, data_center.allocation_state, data_center.uuid, data_center.is_security_group_enabled, data_center.is_local_storage_enabled FROM data_center WHERE data_center.uuid = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,277 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@b0a6700: SELECT data_center.id, data_center.name, data_center.description, data_center.dns1, data_center.dns2, data_center.ip6_dns1, data_center.ip6_dns2, data_center.internal_dns1, data_center.internal_dns2, data_center.router_mac_address, data_center.guest_network_cidr, data_center.domain_id, data_center.domain, data_center.networktype, data_center.dns_provider, data_center.dhcp_provider, data_center.gateway_provider, data_center.vpn_provider, data_center.userdata_provider, data_center.lb_provider, data_center.firewall_provider, data_center.mac_address, data_center.zone_token, data_center.removed, data_center.allocation_state, data_center.uuid, data_center.is_security_group_enabled, data_center.is_local_storage_enabled FROM data_center WHERE data_center.uuid = _binary'6ab6c1a8-6ddd-4dfc-af0d-e3173ae248f2' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,277 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn889695595 2015-01-06 10:24:42,277 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,278 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1749112291. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-GenericDaoBase.findByUuidIncludingRemoved:941-EntityManagerImpl.findByUuidIncludingRemoved:64-GeneratedMethodAccessor260.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,278 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.uuid = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,278 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@196ddf8e: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.uuid = _binary'0d00ce3f-c4b4-4a1e-83f9-a7d3cc33a0d7' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,278 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1749112291 2015-01-06 10:24:42,278 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,279 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn958068852. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.lockRow:963-GenericDaoBase.findById:926-GeneratedMethodAccessor22.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33 2015-01-06 10:24:42,279 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = ? AND account.removed IS NULL 2015-01-06 10:24:42,279 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@19ada4f6: SELECT account.id, account.account_name, account.type, account.domain_id, account.state, account.removed, account.cleanup_needed, account.network_domain, account.uuid, account.default_zone_id, account.default FROM account WHERE account.id = 0 AND account.removed IS NULL 2015-01-06 10:24:42,279 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn958068852 2015-01-06 10:24:42,279 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,280 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn965064781. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.lockRow:963-GenericDaoBase.findById:926-GeneratedMethodAccessor22.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33 2015-01-06 10:24:42,280 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id = ? AND domain.removed IS NULL 2015-01-06 10:24:42,280 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@7a06e4bb: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id = 60 AND domain.removed IS NULL 2015-01-06 10:24:42,280 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn965064781 2015-01-06 10:24:42,280 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,281 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn524548870. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.listIncludingRemovedBy:911-GenericDaoBase.listBy:888-GenericDaoBase.listBy:901-DomainDaoImpl.isChildDomain:253-GeneratedMethodAccessor138.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317 2015-01-06 10:24:42,281 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id IN (?,?) AND domain.removed IS NULL 2015-01-06 10:24:42,281 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@281556f3: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id IN (1,60) AND domain.removed IS NULL 2015-01-06 10:24:42,281 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn524548870 2015-01-06 10:24:42,282 DEBUG [c.c.u.AccountManagerImpl] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Access granted to Acct[bb14e46c-a908-4e5b-b354-d60d9d705c2e-jrapine] to Domain:60/MySecureCloud@69b9ac0f-276a-4aae-9570-7a4f6baf9dfc/AA000069/ by AffinityGroupAccessChecker 2015-01-06 10:24:42,282 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,282 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,285 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn590326880. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.findById:977-GenericDaoBase.lockRow:963-GenericDaoBase.findById:926-GeneratedMethodAccessor22.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33 2015-01-06 10:24:42,286 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id = ? AND domain.removed IS NULL 2015-01-06 10:24:42,286 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@edde4ac: SELECT domain.id, domain.parent, domain.name, domain.owner, domain.path, domain.level, domain.removed, domain.child_count, domain.next_child_seq, domain.state, domain.network_domain, domain.uuid FROM domain WHERE domain.id = 60 AND domain.removed IS NULL 2015-01-06 10:24:42,286 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn590326880 2015-01-06 10:24:42,286 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,286 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1096709173. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.search:340-GenericDaoBase.searchAndCount:1248-GeneratedMethodAccessor129.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150 2015-01-06 10:24:42,287 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT DISTINCT(volume_view.id) FROM volume_view WHERE volume_view.domain_id = ? AND volume_view.account_type != ? AND volume_view.data_center_id = ? AND volume_view.state != ? AND volume_view.system_use != ? AND ( volume_view.vm_type NOT IN (?,?,?) OR volume_view.vm_type IS NULL ) AND volume_view.removed IS NULL ORDER BY volume_view.created DESC LIMIT 0, 20 2015-01-06 10:24:42,298 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) conn: Using existing DB connection 2015-01-06 10:24:42,299 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: select count(distinct id) from volume_view WHERE volume_view.domain_id = ? AND volume_view.account_type != ? AND volume_view.data_center_id = ? AND volume_view.state != ? AND volume_view.system_use != ? AND ( volume_view.vm_type NOT IN (?,?,?) OR volume_view.vm_type IS NULL ) AND volume_view.removed IS NULL 2015-01-06 10:24:42,299 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@201f651a: SELECT DISTINCT(volume_view.id) FROM volume_view WHERE volume_view.domain_id = 60 AND volume_view.account_type != 5 AND volume_view.data_center_id = 1 AND volume_view.state != 'Destroy' AND volume_view.system_use != 1 AND ( volume_view.vm_type NOT IN ('ConsoleProxy','SecondaryStorageVm','DomainRouter') OR volume_view.vm_type IS NULL ) AND volume_view.removed IS NULL ORDER BY volume_view.created DESC LIMIT 0, 20 2015-01-06 10:24:42,317 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@6d269e6f: select count(distinct id) from volume_view WHERE volume_view.domain_id = 60 AND volume_view.account_type != 5 AND volume_view.data_center_id = 1 AND volume_view.state != 'Destroy' AND volume_view.system_use != 1 AND ( volume_view.vm_type NOT IN ('ConsoleProxy','SecondaryStorageVm','DomainRouter') OR volume_view.vm_type IS NULL ) AND volume_view.removed IS NULL 2015-01-06 10:24:42,317 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1096709173 2015-01-06 10:24:42,317 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,317 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : searchByIds, catalina-exec-21, 2015-01-06 10:24:42,321 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1002753126. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-ConfigurationDaoImpl.findByName:231-ConfigurationDaoImpl.getValue:191-GeneratedMethodAccessor54.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,321 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT configuration.instance, configuration.component, configuration.name, configuration.value, configuration.default_value, configuration.description, configuration.category, configuration.is_dynamic, configuration.scope, configuration.updated FROM configuration WHERE configuration.name = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,325 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@75475b31: SELECT configuration.instance, configuration.component, configuration.name, configuration.value, configuration.default_value, configuration.description, configuration.category, configuration.is_dynamic, configuration.scope, configuration.updated FROM configuration WHERE configuration.name = _binary'detail.batch.query.size' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,325 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1002753126 2015-01-06 10:24:42,325 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn2084232199. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-VolumeJoinDaoImpl.searchByIds:302-GeneratedMethodAccessor299.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183-ReflectiveMethodInvocation.proceed:150-TransactionContextInterceptor.invoke:33 2015-01-06 10:24:42,325 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT volume_view.id, volume_view.uuid, volume_view.name, volume_view.device_id, volume_view.volume_type, volume_view.size, volume_view.min_iops, volume_view.max_iops, volume_view.state, volume_view.created, volume_view.attached, volume_view.removed, volume_view.account_id, volume_view.account_uuid, volume_view.account_name, volume_view.account_type, volume_view.domain_id, volume_view.domain_uuid, volume_view.domain_name, volume_view.domain_path, volume_view.project_id, volume_view.project_uuid, volume_view.project_name, volume_view.pod_id, volume_view.data_center_id, volume_view.data_center_uuid, volume_view.data_center_name, volume_view.vm_id, volume_view.vm_uuid, volume_view.vm_name, volume_view.vm_display_name, volume_view.vm_state, volume_view.vm_type, volume_view.volume_store_size, volume_view.created_on_store, volume_view.format, volume_view.download_pct, volume_view.download_state, volume_view.error_str, volume_view.hypervisor_type, volume_view.disk_offering_id, volume_view.disk_offering_uuid, volume_view.disk_offering_name, volume_view.disk_offering_display_text, volume_view.system_use, volume_view.use_local_storage, volume_view.bytes_read_rate, volume_view.bytes_write_rate, volume_view.iops_read_rate, volume_view.iops_write_rate, volume_view.pool_id, volume_view.pool_uuid, volume_view.pool_name, volume_view.template_id, volume_view.template_uuid, volume_view.extractable, volume_view.template_type, volume_view.job_id, volume_view.job_uuid, volume_view.job_status, volume_view.tag_id, volume_view.tag_uuid, volume_view.tag_key, volume_view.tag_value, volume_view.tag_domain_id, volume_view.tag_account_id, volume_view.tag_resource_id, volume_view.tag_resource_uuid, volume_view.tag_resource_type, volume_view.tag_customer, volume_view.display_volume, volume_view.path FROM volume_view WHERE volume_view.id IN (?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?,?) 2015-01-06 10:24:42,369 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.JDBC4PreparedStatement@7c438f48: SELECT volume_view.id, volume_view.uuid, volume_view.name, volume_view.device_id, volume_view.volume_type, volume_view.size, volume_view.min_iops, volume_view.max_iops, volume_view.state, volume_view.created, volume_view.attached, volume_view.removed, volume_view.account_id, volume_view.account_uuid, volume_view.account_name, volume_view.account_type, volume_view.domain_id, volume_view.domain_uuid, volume_view.domain_name, volume_view.domain_path, volume_view.project_id, volume_view.project_uuid, volume_view.project_name, volume_view.pod_id, volume_view.data_center_id, volume_view.data_center_uuid, volume_view.data_center_name, volume_view.vm_id, volume_view.vm_uuid, volume_view.vm_name, volume_view.vm_display_name, volume_view.vm_state, volume_view.vm_type, volume_view.volume_store_size, volume_view.created_on_store, volume_view.format, volume_view.download_pct, volume_view.download_state, volume_view.error_str, volume_view.hypervisor_type, volume_view.disk_offering_id, volume_view.disk_offering_uuid, volume_view.disk_offering_name, volume_view.disk_offering_display_text, volume_view.system_use, volume_view.use_local_storage, volume_view.bytes_read_rate, volume_view.bytes_write_rate, volume_view.iops_read_rate, volume_view.iops_write_rate, volume_view.pool_id, volume_view.pool_uuid, volume_view.pool_name, volume_view.template_id, volume_view.template_uuid, volume_view.extractable, volume_view.template_type, volume_view.job_id, volume_view.job_uuid, volume_view.job_status, volume_view.tag_id, volume_view.tag_uuid, volume_view.tag_key, volume_view.tag_value, volume_view.tag_domain_id, volume_view.tag_account_id, volume_view.tag_resource_id, volume_view.tag_resource_uuid, volume_view.tag_resource_type, volume_view.tag_customer, volume_view.display_volume, volume_view.path FROM volume_view WHERE volume_view.id IN (4148,4125,4124,4099,4098,3943,3296,3153,3151,3150,3148,3146,3007,3006,3005,3003,3001,2973,2972,2971) 2015-01-06 10:24:42,370 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn2084232199 2015-01-06 10:24:42,370 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,370 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : newVolumeResponse, catalina-exec-21, 2015-01-06 10:24:42,371 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1940959015. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-GenericDaoBase.findOneBy:880-ResourceDetailsDaoBase.findDetail:46-GeneratedMethodAccessor100.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,371 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = ? AND volume_details.name = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,373 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@54bb64c7: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = 2971 AND volume_details.name = _binary'pcidevicepath' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,373 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1940959015 2015-01-06 10:24:42,373 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,373 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : newVolumeResponse, catalina-exec-21, 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn2037365023. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-GenericDaoBase.findOneBy:880-ResourceDetailsDaoBase.findDetail:46-GeneratedMethodAccessor100.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = ? AND volume_details.name = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@15e2e082: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = 2972 AND volume_details.name = _binary'pcidevicepath' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn2037365023 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,374 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : newVolumeResponse, catalina-exec-21, 2015-01-06 10:24:42,375 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Creating a DB connection with no txn: for 0: dbconn1279583927. Stack: -TransactionLegacy.prepareStatement:467-TransactionLegacy.prepareAutoCloseStatement:460-GenericDaoBase.searchIncludingRemoved:394-GenericDaoBase.searchIncludingRemoved:356-GenericDaoBase.findOneIncludingRemovedBy:869-GenericDaoBase.findOneBy:880-ResourceDetailsDaoBase.findDetail:46-GeneratedMethodAccessor100.invoke:-1-DelegatingMethodAccessorImpl.invoke:43-Method.invoke:622-AopUtils.invokeJoinpointUsingReflection:317-ReflectiveMethodInvocation.invokeJoinpoint:183 2015-01-06 10:24:42,375 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Preparing: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = ? AND volume_details.name = ? ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,378 TRACE [c.c.u.d.T.Statement] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing: com.mysql.jdbc.PreparedStatement@3f871f7f: SELECT volume_details.id, volume_details.volume_id, volume_details.name, volume_details.value, volume_details.display FROM volume_details WHERE volume_details.volume_id = 2973 AND volume_details.name = _binary'pcidevicepath' ORDER BY RAND() LIMIT 1 2015-01-06 10:24:42,378 TRACE [c.c.u.d.T.Connection] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Closing DB connection: dbconn1279583927 2015-01-06 10:24:42,378 TRACE [c.c.u.d.T.Transaction] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) Using current transaction: catalina-exec-21 : catalina-exec-21, 2015-01-06 10:24:42,378 ERROR [c.c.a.ApiServer] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) unhandled exception executing api command: listVolumes java.lang.NullPointerException at com.cloud.api.query.dao.VolumeJoinDaoImpl.newVolumeResponse(VolumeJoinDaoImpl.java:103) at sun.reflect.GeneratedMethodAccessor248.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:622) 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 com.cloud.utils.db.TransactionContextInterceptor.invoke(TransactionContextInterceptor.java:33) at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:161) 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.$Proxy293.newVolumeResponse(Unknown Source) at com.cloud.api.ApiDBUtils.newVolumeResponse(ApiDBUtils.java:1566) at com.cloud.api.query.ViewResponseHelper.createVolumeResponse(ViewResponseHelper.java:271) at com.cloud.api.query.QueryManagerImpl.searchForVolumes(QueryManagerImpl.java:1647) at org.apache.cloudstack.api.command.user.volume.ListVolumesCmd.execute(ListVolumesCmd.java:130) at com.cloud.api.ApiDispatcher.dispatch(ApiDispatcher.java:167) at com.cloud.api.ApiServer.queueCommand(ApiServer.java:630) at com.cloud.api.ApiServer.handleRequest(ApiServer.java:469) at com.cloud.api.ApiServlet.processRequestInContext(ApiServlet.java:322) at com.cloud.api.ApiServlet.access$000(ApiServlet.java:52) at com.cloud.api.ApiServlet$1.run(ApiServlet.java:114) 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 com.cloud.api.ApiServlet.processRequest(ApiServlet.java:111) at com.cloud.api.ApiServlet.doGet(ApiServlet.java:73) at javax.servlet.http.HttpServlet.service(HttpServlet.java:617) at javax.servlet.http.HttpServlet.service(HttpServlet.java:717) at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290) at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206) at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233) at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191) at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127) at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102) at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:555) at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109) at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:298) at org.apache.coyote.http11.Http11NioProcessor.process(Http11NioProcessor.java:889) at org.apache.coyote.http11.Http11NioProtocol$Http11ConnectionHandler.process(Http11NioProtocol.java:721) at org.apache.tomcat.util.net.NioEndpoint$SocketProcessor.run(NioEndpoint.java:2274) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1146) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) at java.lang.Thread.run(Thread.java:701) 2015-01-06 10:24:42,379 TRACE [c.c.a.r.ApiResponseSerializer] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) ===Serializing Response=== 2015-01-06 10:24:42,379 DEBUG [c.c.a.ApiServlet] (catalina-exec-21:ctx-76704af2 ctx-c919dc80) ===END=== 10.111.1.3 -- GET command=listVolumes&response=json&sessionkey=PfDwFth3hoqeE1cgIUkfBWRHpgo%3D&zoneid=6ab6c1a8-6ddd-4dfc-af0d-e3173ae248f2&domainid=0d00ce3f-c4b4-4a1e-83f9-a7d3cc33a0d7&listAll=true&page=1&pagesize=20&_=1420557899743 -- This message was sent by Atlassian JIRA (v6.3.4#6332)