Add-on v3.0.0 not working (NPE)

Issue #13 resolved
Andreas Pohl created an issue

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)

  1. [NPES] 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.

  2. 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 ?

  3. Andreas Pohl reporter

    Hi,

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

    Regards,

    Andreas

  4. [NPES] 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”

  5. [NPES] 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?

  6. 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

  7. Andreas Pohl reporter

    Hi,

    What’s the status of this issue?

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

    Regards, Andreas

  8. 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

  9. [NPES] 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.

  10. 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

  11. 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
    ...
    

  12. [NPES] 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')

  13. [NPES] Alexey Dorofeyev repo owner

    I think we found the cause of the error. Please try attached version of the app.

  14. 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.
    

  15. [NPES] 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

  16. 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!

  17. Log in to comment