rocktronic / Custom Field Toolbox for Jira / issues / #13 - Add-on v3.0.0 not working (NPE) — Bitbucket

Issue #13 resolved

Andreas Pohl created an issue 2019-08-22

Hi,

The add-on v3.0.0 doesn’t work with Jira Software Server v8.2.4.

The menu item Issues → Fields keeps constantly loading, but doesn’t show any information.
The atlassian-jira.log file shows a NullPointerException, as you can see in the attached file.

Any idea how to fix this issue?
We’d like to keep on using the add-on.

Cheers,

Andreas

Comments (25)

Andreas Pohl reporter
- edited description
- 2019-08-22T07:54:12+00:00
[ANS] Alexey Dorofeyev repo owner
Hi Andreas,

What database you are using?
- 2019-08-26T20:28:12+00:00
Andreas Pohl reporter
MS SQL Server 2012
- 2019-08-27T06:39:30+00:00
[ANS] Alexey Dorofeyev repo owner
Hi Andreas,

We're unable to reproduce this bug, but we’re made some code changes in fresh release 3.0.1, which may help.

If NullPointerException is still there in 3.0.1, please change log level for package systems.npe.jira.cfu.api.shared.FieldManager to DEBUG and provide us a part of log with this error.
- 2019-11-22T19:20:27+00:00
Andreas Pohl reporter
Hi Alexey, The bug still exists with v3.0.1. The menu item “Field types” works correctly, but the menu item “Fields” shows an infinitely spinnig wheel (loading) while the log file logs a java.lang.NullPointerException (same as attached).

How/where can I change the log level for package systems.npe.jira.cfu.api.shared.FieldManager ?
- 2019-11-25T07:25:18+00:00
Andreas Pohl reporter
Hi,

How/where can I change the log level for package systems.npe.jira.cfu.api.shared.FieldManager?

Regards,

Andreas
- 2019-12-04T07:35:13+00:00
[ANS] Alexey Dorofeyev repo owner
1. Gog → System → Logging and profiling
2. Click “Configure logging level for another package.”
3. Package name: systems.npe.jira.cfu.api.shared.FieldManager
4. Logging level: DEBUG
5. Click “Add”
‌
- 2019-12-04T20:21:22+00:00
Andreas Pohl reporter
- attached CFU_endless_loading.PNG
- attached CFU.log
Attached you'll find the log file and a screenshot of the endlessly loading Custom Field Manager screen.
- 2019-12-06T10:15:08+00:00
[ANS] Alexey Dorofeyev repo owner
Ok, now we’ve got an id of problem field, it’s 10872. You can view it here: YOUR_JIRA_BASE_URL/secure/admin/ConfigureCustomField!default.jspa?customFieldId=10872

What type of field it is? Is there any any special about it?
- 2019-12-09T19:21:49+00:00
Andreas Pohl reporter
Hi,

CustomField 10872 is the locked Jira standard field „Epic Link” (of type “Epic Link Relationship”).

[cid:image001.png@01D5B0D8.289EFBF0]

I guess there shouldn’t be any problem with Jira fields like that.

Regards Andreas
- 2019-12-12T09:40:29+00:00
Andreas Pohl reporter
Hi,

What’s the status of this issue?

We’re still not able to use the purchased plugin..

Regards, Andreas
- 2020-02-17T10:30:14+00:00
Andreas Pohl reporter
Please let us know the status of this issue.

We’re still not able to use the purchased plugin and we won’t renew the license if you keep ignoring our support requests.

Regards, Andreas
- 2020-03-17T08:55:27+00:00
[ANS] Alexey Dorofeyev repo owner
Hi Andreas,

I’m very sorry, for some reason, we stopped receiving notifications from Bitbucket and missed your reply. Today we released a major update of our application, please update to it and try to open the application page again. If the error persists, please enable DEBUG logging mode for the entire application by specifying package name “systems.npe.jira.cfu”, try again and send the log.

‌
- 2020-05-08T18:26:15+00:00

Andreas Pohl reporter

Hi,

I installed the current version 4.0.1 on our test system.

The progress bar gets stuck:

I enabled debug logging as suggested.
Please find an excerpt of the log file attached.

The messages are over and over the same.
The “Current percent” value remains unchanged:

2020-05-11 08:46:17,696 http-nio-8009-exec-13 DEBUG USER 526x222541x2 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:17,696 http-nio-8009-exec-13 DEBUG USER 526x222541x2 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:17,839 http-nio-8009-exec-15 DEBUG USER 526x222542x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:17,839 http-nio-8009-exec-15 DEBUG USER 526x222542x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:17,974 http-nio-8009-exec-18 DEBUG USER 526x222543x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:17,974 http-nio-8009-exec-18 DEBUG USER 526x222543x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,110 http-nio-8009-exec-17 DEBUG USER 526x222545x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,110 http-nio-8009-exec-17 DEBUG USER 526x222545x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,257 http-nio-8009-exec-23 DEBUG USER 526x222546x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,257 http-nio-8009-exec-23 DEBUG USER 526x222546x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,402 http-nio-8009-exec-9 DEBUG USER 526x222547x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,402 http-nio-8009-exec-9 DEBUG USER 526x222547x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,544 http-nio-8009-exec-10 DEBUG USER 526x222548x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,544 http-nio-8009-exec-10 DEBUG USER 526x222548x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,680 http-nio-8009-exec-5 DEBUG USER 526x222549x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,680 http-nio-8009-exec-5 DEBUG USER 526x222549x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,812 http-nio-8009-exec-25 DEBUG USER 526x222550x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,812 http-nio-8009-exec-25 DEBUG USER 526x222550x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:18,944 http-nio-8009-exec-12 DEBUG USER 526x222551x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:18,944 http-nio-8009-exec-12 DEBUG USER 526x222551x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,078 http-nio-8009-exec-11 DEBUG USER 526x222553x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:19,078 http-nio-8009-exec-11 DEBUG USER 526x222553x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,215 http-nio-8009-exec-2 DEBUG USER 526x222554x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:19,215 http-nio-8009-exec-2 DEBUG USER 526x222554x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,346 http-nio-8009-exec-16 DEBUG USER 526x222555x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:19,346 http-nio-8009-exec-16 DEBUG USER 526x222555x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,477 http-nio-8009-exec-22 DEBUG USER 526x222556x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:19,477 http-nio-8009-exec-22 DEBUG USER 526x222556x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,606 http-nio-8009-exec-4 DEBUG USER 526x222557x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:46:19,606 http-nio-8009-exec-4 DEBUG USER 526x222557x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:46:19,739 http-nio-8009-exec-24 DEBUG USER 526x222558x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
...
2020-05-11 08:47:15,434 http-nio-8009-exec-17 DEBUG USER 527x222968x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:47:15,574 http-nio-8009-exec-10 DEBUG USER 527x222970x2 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:47:15,574 http-nio-8009-exec-10 DEBUG USER 527x222970x2 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:47:15,710 http-nio-8009-exec-9 DEBUG USER 527x222971x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:47:15,710 http-nio-8009-exec-9 DEBUG USER 527x222971x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 08:47:15,886 http-nio-8009-exec-5 DEBUG USER 527x222973x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 08:47:15,886 http-nio-8009-exec-5 DEBUG USER 527x222973x1 u3m9ri <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
~

I stopped the logging after a while.

Regards,
Andreas

‌

2020-05-11T06:59:35+00:00

[ANS] Alexey Dorofeyev repo owner
Ok, i need a part of the log that was before “Current percent: 0.35353535353535354” began to repeat
- 2020-05-11T07:37:50+00:00

Andreas Pohl reporter

2020-05-11 09:38:38,916 plugin-transaction-0 INFO      [c.a.jira.plugin.PluginTransactionListener] [plugin-transaction] numberStartEvents:875, numberEndEvents:875, numberSendEvents:481, numberEventsInTransactions:15454, numberOfPluginEnableEvents:290
2020-05-11 09:39:00,005 Caesium-1-1 DEBUG anonymous    Debug [c.a.j.service.services.DebugService] DebugService.run
2020-05-11 09:39:43,753 http-nio-8009-exec-16 INFO USER 579x225451x1 11jcz7s <IPs sanitized> /secure/admin/WebSudoAuthenticate.jspa [c.a.jira.index.MonitoringIndexWriter] [lucene-stats] flush stats: snapshotCount=3, totalCount=947, periodSec=989, flushIntervalMillis=329927, indexDirectory=null, indexWriterId=com.atlassian.jira.index.MonitoringIndexWriter@30630364, indexDirectoryId=RAMDirectory@11b1e4d5 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5818e784
2020-05-11 09:39:58,641 ThreadPoolAsyncTaskExecutor::Thread 36 WARN USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [o.s.b.factory.support.DefaultListableBeanFactory] Overriding user-defined bean definition for bean 'eventPublisher' with a framework-generated bean definition: replacing [Generic bean: class [com.atlassian.plugin.osgi.bridge.external.HostComponentFactoryBean]; scope=; abstract=false; lazyInit=true; autowireMode=0; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null; defined in URL [bundle://271.0:0/META-INF/spring/atlassian-plugins-host-components.xml]] with [Root bean: class [org.eclipse.gemini.blueprint.service.importer.support.OsgiServiceProxyFactoryBean]; scope=; abstract=false; lazyInit=false; autowireMode=3; dependencyCheck=0; autowireCandidate=true; primary=false; factoryBeanName=null; factoryMethodName=null; initMethodName=null; destroyMethodName=null]
2020-05-11 09:39:58,655 http-nio-8009-exec-8 INFO USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.plugin.util.WaitUntil] Plugins that have yet to be enabled: (1): [systems.npe.jira.cfu], 300 seconds remaining
2020-05-11 09:39:59,660 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.OsgiServiceUtilsImpl] Registering service net.java.ao.atlassian.AtlassianTableNameConverter@773396af with interface net.java.ao.schema.TableNameConverter and properties {com.atlassian.plugin.key=systems.npe.jira.cfu}
2020-05-11 09:39:59,662 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.OsgiServiceUtilsImpl] Registering service com.atlassian.activeobjects.config.internal.DefaultActiveObjectsConfiguration@a62bb2c9 with interface com.atlassian.activeobjects.config.ActiveObjectsConfiguration and properties {com.atlassian.plugin.key=systems.npe.jira.cfu}
2020-05-11 09:39:59,664 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.ActiveObjectsServiceFactory] onPluginModuleEnabledEvent storing unattached <ao> configuration module for [systems.npe.jira.cfu]
2020-05-11 09:39:59,732 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.ActiveObjectsServiceFactory] onPluginEnabledEvent attaching unbound <ao> to [systems.npe.jira.cfu]
2020-05-11 09:39:59,732 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.TenantAwareActiveObjects] init bundle [systems.npe.jira.cfu]
2020-05-11 09:39:59,732 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.TenantAwareActiveObjects] bundle [systems.npe.jira.cfu] loading new AO promise for JiraTenantImpl{id='system'}
2020-05-11 09:39:59,732 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.TenantAwareActiveObjects] setAoConfiguration [systems.npe.jira.cfu]
2020-05-11 09:39:59,732 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [c.a.activeobjects.osgi.TenantAwareActiveObjects] bundle [systems.npe.jira.cfu] got ActiveObjectsConfiguration
2020-05-11 09:39:59,735 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.api.configuration.ConfigurationManager] Trying to get configuration object with key systems.npe.jira.cfu-audit-configuration from Jira properties, but there's no one
2020-05-11 09:39:59,735 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.field.audit.AuditRules] Initializing rule: class systems.npe.jira.cfu.api.field.audit.rules.UnusedFields
2020-05-11 09:39:59,736 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.field.audit.AuditRules] Rule instance: systems.npe.jira.cfu.api.field.audit.rules.UnusedFields@53b4afc9
2020-05-11 09:39:59,736 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.f.audit.rules.UnusedFields] Setting field is used audit rule params!
2020-05-11 09:39:59,738 active-objects-init-JiraTenantImpl{id='system'}-0 DEBUG USER     [c.a.activeobjects.osgi.TenantAwareActiveObjects] bundle [systems.npe.jira.cfu] creating ActiveObjects
2020-05-11 09:39:59,788 active-objects-init-JiraTenantImpl{id='system'}-0 DEBUG USER     [c.a.activeobjects.osgi.TenantAwareActiveObjects] bundle [systems.npe.jira.cfu] created ActiveObjects
2020-05-11 09:40:00,014 Caesium-1-1 DEBUG anonymous    Debug [c.a.j.service.services.DebugService] DebugService.run
2020-05-11 09:40:00,445 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.field.audit.AuditRules] Initializing rule: class systems.npe.jira.cfu.api.field.audit.rules.LostFields
2020-05-11 09:40:00,446 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.field.audit.AuditRules] Rule instance: systems.npe.jira.cfu.api.field.audit.rules.LostFields@5d74e0d2
2020-05-11 09:40:00,446 http-nio-8009-exec-8 DEBUG USER 579x225501x1 4m7i4p <IPs sanitized> /rest/plugins/1.0/systems.npe.jira.cfu-key [s.n.j.c.a.f.audit.rules.LostFields] Setting 'Field is lost' audit rule params!
2020-05-11 09:40:28,059 http-nio-8009-exec-1 DEBUG USER 580x225549x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 1
2020-05-11 09:40:28,063 http-nio-8009-exec-1 DEBUG USER 580x225549x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Job is planned
2020-05-11 09:40:28,068 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldListRestService] Starting job
2020-05-11 09:40:28,068 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Fetching all fields
2020-05-11 09:40:28,068 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Processing statement: SELECT ID, cfname, DESCRIPTION, CUSTOMFIELDTYPEKEY FROM dbo.customfield order by cfname
2020-05-11 09:40:28,069 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field ID to 12683
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field searcher to Free Text Searcher
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field type name to Text Field (multi-line)
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field provider to Custom Field Types & Searchers
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field description to null
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field type name to com.atlassian.jira.plugin.system.customfieldtypes:textarea
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field ID to 11370
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field searcher to Date Time Range picker
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field type name to Date Time Picker
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field provider to Custom Field Types & Searchers
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field description to for ILS Small Order
2020-05-11 09:40:28,070 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field type name to com.atlassian.jira.plugin.system.customfieldtypes:datetime
...
2020-05-11 09:40:28,422 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Processing statement: SELECT FILTER as value from dbo.AO_4AEACD_WEBHOOK_DAO
2020-05-11 09:40:28,423 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Returned value is []
2020-05-11 09:40:28,423 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Processing statement: SELECT  ITEM_ID as value  from dbo.managedconfigurationitem WHERE ITEM_TYPE = 'CUSTOM_FIELD' AND ACCESS_LEVEL = 'LOCKED'
2020-05-11 09:40:28,423 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Returned value is [customfield_11570, customfield_10870, customfield_10872, customfield_10874, customfield_10873, customfield_10875, customfield_10871, customfield_11980, customfield_11981, customfield_11970, customfield_12295, customfield_12296, customfield_12297, customfield_12370, customfield_13071, customfield_13297, customfield_13300, customfield_13372]
2020-05-11 09:40:28,427 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 12683
2020-05-11 09:40:28,427 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=12683
2020-05-11 09:40:28,427 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 4
2020-05-11 09:40:28,427 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] DB values: 241
2020-05-11 09:40:28,427 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field database values count to 241
2020-05-11 09:40:28,428 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WF values: 0
2020-05-11 09:40:28,428 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field workflow values count to 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] SF values: 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field saved filters value count to 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WH values: 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field webhooks values count to 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field locked status to false
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.UnusedFields] Making 'Field is used' audit for field Acceptance Criterion
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.LostFields] Making 'Field is lost' audit for field Acceptance Criterion
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 11370
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=11370
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 0
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] DB values: 180
2020-05-11 09:40:28,429 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field database values count to 180
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WF values: 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field workflow values count to 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] SF values: 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field saved filters value count to 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WH values: 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field webhooks values count to 0
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field locked status to false
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.UnusedFields] Making 'Field is used' audit for field Acceptance Date
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.LostFields] Making 'Field is lost' audit for field Acceptance Date
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 10070
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=10070
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 3
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] DB values: 75446
2020-05-11 09:40:28,430 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field database values count to 75446
2020-05-11 09:40:28,431 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WF values: 1
2020-05-11 09:40:28,431 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field workflow values count to 1
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] SF values: 2
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field saved filters value count to 2
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WH values: 0
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field webhooks values count to 0
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field locked status to false
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.UnusedFields] Making 'Field is used' audit for field Actions Performed
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.LostFields] Making 'Field is lost' audit for field Actions Performed
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 13678
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=13678
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 0
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] DB values: 5
2020-05-11 09:40:28,432 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field database values count to 5
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WF values: 1
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field workflow values count to 1
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] SF values: 0
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field saved filters value count to 0
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WH values: 0
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field webhooks values count to 0
2020-05-11 09:40:28,433 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field locked status to false
...
2020-05-11 09:40:28,576 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 10875
2020-05-11 09:40:28,576 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=10875
2020-05-11 09:40:28,576 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 0
2020-05-11 09:40:28,576 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] DB values: 5115
2020-05-11 09:40:28,576 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field database values count to 5115
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WF values: 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field workflow values count to 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] SF values: 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field saved filters value count to 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] WH values: 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field webhooks values count to 0
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Field is locked
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field locked status to true
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.UnusedFields] Making 'Field is used' audit for field Epic Colour
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.a.f.audit.rules.LostFields] Making 'Field is lost' audit for field Epic Colour
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.FieldManager] Processing field 10872
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field details url to /secure/admin/CFUFieldDetails.jspa?field=10872
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.field.FieldModel] Setting field screens count to 33
2020-05-11 09:40:28,577 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Processing statement: SELECT COUNT(id) as value FROM dbo.issuelink WHERE LINKTYPE = (SELECT ID FROM dbo.issuelinktype WHERE pstyle='jira_gh_epic_story')
2020-05-11 09:40:28,578 Caesium-1-3 ERROR ServiceRunner     [s.n.j.c.api.shared.SQLUtil]
2020-05-11 09:40:28,578 Caesium-1-3 DEBUG ServiceRunner     [s.n.j.c.api.shared.SQLUtil] Returned value is null
2020-05-11 09:40:28,579 Caesium-1-3 ERROR ServiceRunner     [c.a.scheduler.core.JobLauncher] Scheduled job with ID '3eca5720-a24c-4529-a501-a13d985706c8' failed
java.lang.NullPointerException
        at systems.npe.jira.cfu.api.shared.FieldManager.getDbValuesCount(FieldManager.java:91)
        at systems.npe.jira.cfu.api.shared.FieldManager.getFields(FieldManager.java:66)
        at systems.npe.jira.cfu.api.field.FieldListRestService.runJob(FieldListRestService.java:88)
        at com.atlassian.scheduler.core.JobLauncher.runJob(JobLauncher.java:134)
        at com.atlassian.scheduler.core.JobLauncher.launchAndBuildResponse(JobLauncher.java:106)
        at com.atlassian.scheduler.core.JobLauncher.launch(JobLauncher.java:90)
        at com.atlassian.scheduler.caesium.impl.CaesiumSchedulerService.launchJob(CaesiumSchedulerService.java:435)
        at com.atlassian.scheduler.caesium.impl.CaesiumSchedulerService.executeClusteredJob(CaesiumSchedulerService.java:430)
        at com.atlassian.scheduler.caesium.impl.CaesiumSchedulerService.executeClusteredJobWithRecoveryGuard(CaesiumSchedulerService.java:454)
        at com.atlassian.scheduler.caesium.impl.CaesiumSchedulerService.executeQueuedJob(CaesiumSchedulerService.java:382)
        at com.atlassian.scheduler.caesium.impl.SchedulerQueueWorker.executeJob(SchedulerQueueWorker.java:66)
        at com.atlassian.scheduler.caesium.impl.SchedulerQueueWorker.executeNextJob(SchedulerQueueWorker.java:60)
        at com.atlassian.scheduler.caesium.impl.SchedulerQueueWorker.run(SchedulerQueueWorker.java:35)
        at java.lang.Thread.run(Thread.java:748)
2020-05-11 09:40:28,623 http-nio-8009-exec-15 DEBUG USER 580x225554x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:28,623 http-nio-8009-exec-15 DEBUG USER 580x225554x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 09:40:28,759 http-nio-8009-exec-22 DEBUG USER 580x225555x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:28,760 http-nio-8009-exec-22 DEBUG USER 580x225555x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 09:40:28,893 http-nio-8009-exec-23 DEBUG USER 580x225556x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:28,893 http-nio-8009-exec-23 DEBUG USER 580x225556x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 09:40:29,025 http-nio-8009-exec-19 DEBUG USER 580x225557x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:29,025 http-nio-8009-exec-19 DEBUG USER 580x225557x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 09:40:29,158 http-nio-8009-exec-9 DEBUG USER 580x225558x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:29,158 http-nio-8009-exec-9 DEBUG USER 580x225558x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
2020-05-11 09:40:29,291 http-nio-8009-exec-6 DEBUG USER 580x225559x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] State: 2
2020-05-11 09:40:29,291 http-nio-8009-exec-6 DEBUG USER 580x225559x1 4m7i4p <IPs sanitized> /rest/cfu/1.0/fieldWithJob [s.n.j.c.api.field.FieldListRestService] Current percent: 0.35353535353535354
...

‌

2020-05-11T08:00:16+00:00

[ANS] Alexey Dorofeyev repo owner
Can you execute this query directly on your database?
SELECT COUNT(id) as value FROM dbo.issuelink WHERE LINKTYPE = (SELECT ID FROM dbo.issuelinktype WHERE pstyle='jira_gh_epic_story')
- 2020-05-11T10:49:09+00:00
[ANS] Alexey Dorofeyev repo owner
- attached cfu-4.0.2.obr
- 2020-05-11T12:37:35+00:00
[ANS] Alexey Dorofeyev repo owner
I think we found the cause of the error. Please try attached version of the app.
- 2020-05-11T12:38:55+00:00

Andreas Pohl reporter

The new version 4.0.2 does not work as well - the progress bar still gets stuck.

The SQL command shows the following result:

Msg 512, Level 16, State 1, Line 1
Subquery returned more than 1 value. This is not permitted when the subquery follows =, !=, <, <= , >, >= or when the subquery is used as an expression.

‌

2020-05-11T13:30:59+00:00

[ANS] Alexey Dorofeyev repo owner
Hmm.. it looks like you have several fields of type “Epic link“.

Can you please execute

SELECT * FROM dbo.issuelinktype WHERE pstyle='jira_gh_epic_story
- 2020-05-11T14:18:06+00:00
Andreas Pohl reporter
Correct, we have 2:

‌
- 2020-05-11T15:01:40+00:00
[ANS] Alexey Dorofeyev repo owner
I recommend you fix it:

https://jira.atlassian.com/browse/JSWSERVER-10243

https://confluence.atlassian.com/jirakb/all-epic-link-data-appears-to-be-lost-779158624.html

Since this is a bug, we see no reason to adapt our app to work correctly with it.
- 2020-05-11T16:57:07+00:00
Andreas Pohl reporter
We fixed it by deleting the link not in use.

The add-on works now. This issue can be closed.

Thank you!
- 2020-05-13T11:08:07+00:00
[ANS] Alexey Dorofeyev repo owner
- changed status to resolved
- 2020-05-13T11:46:10+00:00
Log in to comment

Assignee: –

Type: bug

Priority: major

Status: resolved

Votes: 0

Watchers: 1

Jira: the preferred issue tracker for Bitbucket. Join the team!