search

wolfgangimig / itol

JOA based issue tracker for Microsoft Outlook
23 stars 6 forks source link

Can't get it to work with Outlook 2013 64-bit #15

Closed dan-sorion closed 9 years ago

dan-sorion commented 9 years ago

I've installed/uninstalled this plug-in a few times now, no matter what I do I can't get the JOA plug-in to load in Outlook 2013 64-bit. The Issue Tracker plug-in loads, I can set the options and it creates the project in Redmine successfully, and the log shows it retrieving all of the tracker details from the server via JSON.

When I click the Issue Pane button nothing happens. In the log it shows the following:

2015-03-10 15:28:31,498 FINE com.wilutions.itol.ItolAddin Button_onAction Button_onAction(true

but nothing appears. In the COM Add-ins in Outlook the JOA add-in shows as not loaded with a blank location and 

Load Behaviour: Not loaded. A runtime error occurred during the loading of the COM Add-in.

If I try to add this DLL manually using the Add button in the COM Add-ins dialog I get a pop-up with

'C:\Users\<my name>\AppData\Local\Issue Tracker for Microsoft Outlook 64bit\app\joa64.dll' is not a valid Office Add-in.

I've double checked that I'm using the 64-bit installer Issue Tracker for Microsoft Outlook 64bit-1.2.msi. There is nothing in the itol.log file mentioning JOA.

Is there anything else I need to do in order to get this working?

Regards,

Dan

wolfgangimig commented 9 years ago

Hello Dan,

the problem seems to be related to the communication with Redmine. The installation and registration of the Addin works fine, otherwise you wouldn't see the backstage UI e.g. Which Redmine version do you use?

Regards Wolfgang

dan-sorion commented 9 years ago

Hi Wolfgang,

Thanks for getting in touch so quickly.

I don’t see any errors in the command window. I have attached the log file (with some information like URLs, API keys, etc obscured), looking at it I can see that it connects to Redmine ok and gets all the project data JSON ok. I’m using Windows 7 Professional 64-bit with Outlook 2013 64-bit, and Redmine 2.6.2 which is running on Windows 2008 64-bit under IIS via the Helicon Zoo platform installer. I only have a few plugins installed which should not affect the JSON output from Redmine – Agile (Light version), Better Gantt Chart, Checklists (Light version), Projects Accordion, and Todo Lists.

This Redmine install has very little included, it's a development system for testing so is close to vanilla install.

Anything else I can try? Clicking the Issue Pane button just makes the button background turn blue, nothing else happens.

Regards,

Dan

Log:

2015-03-11 07:43:25,849 INFO com.wilutions.itol.Globals initLogging Logger initialized
2015-03-11 07:43:26,291 FINE com.wilutions.itol.Globals initIssueService initIssueService(C:\Users\<me>\AppData\Local\Issue Tracker for Microsoft Outlook 64bit\app
2015-03-11 07:43:28,076 FINE com.wilutions.itol.Globals initIssueService )initIssueService
2015-03-11 07:43:28,077 FINE com.wilutions.itol.Globals lambda$0 issueService.setConfig
2015-03-11 07:43:28,132 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize initialize(
2015-03-11 07:43:28,135 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize config.url=http://<server>:<port>
2015-03-11 07:43:28,139 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize config.apiKey set=40
2015-03-11 07:43:28,144 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize config.msgFileType=.msg
2015-03-11 07:43:28,153 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readOrUpdateConfigurationProject
2015-03-11 07:43:28,156 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readOrUpdateConfigurationProject readOrUpdateConfigurationProject(
2015-03-11 07:43:28,174 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/projects/itol-configuration.json
2015-03-11 07:43:28,175 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:28,175 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:28,342 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/projects/itol-configuration.json #-1
2015-03-11 07:43:28,343 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:29,331 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@e12e21e6 pairs: {GET /projects/itol-configuration.json HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:29,551 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@379567fd12 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "2726e01650117fc96fefb0705485fd94"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: afc79b7116c90cd1d80cff7a8ac3fe76}{X-Runtime: 0.031201}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:29 GMT}{Content-Length: 506}
2015-03-11 07:43:29,619 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:29,622 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:29,623 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:29,624 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:29,625 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:29,626 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[afc79b7116c90cd1d80cff7a8ac3fe76]
2015-03-11 07:43:29,627 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["2726e01650117fc96fefb0705485fd94"]
2015-03-11 07:43:29,628 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.031201]
2015-03-11 07:43:29,629 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[506]
2015-03-11 07:43:29,630 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:29 GMT]
2015-03-11 07:43:29,631 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:29,632 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:29,633 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:29,634 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:29,644 INFO com.wilutions.itol.db.HttpClient send 200 #506
2015-03-11 07:43:29,649 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: afc79b7116c90cd1d80cff7a8ac3fe76, ETag: "2726e01650117fc96fefb0705485fd94", X-Runtime: 0.031201, Content-Length: 506, Date: Wed, 11 Mar 2015 07:43:29 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"project":{"id":20,"name":"Issue Tracker for Microsoft Outlook and Redmine Configuration","identifier":"itol-configuration","description":"This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:29:22.959Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>","homepage":"","status":1,"is_public":true,"created_on":"2015-03-10T15:18:25Z","updated_on":"2015-03-11T07:29:23Z"}}]
2015-03-11 07:43:29,683 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl idump configProject={
  "id": 20,
  "name": "Issue Tracker for Microsoft Outlook and Redmine Configuration",
  "identifier": "itol-configuration",
  "description": "This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:29:22.959Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>",
  "homepage": "",
  "status": 1,
  "is_public": true,
  "created_on": "2015-03-10T15:18:25Z",
  "updated_on": "2015-03-11T07:29:23Z"
}
2015-03-11 07:43:33,132 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump data.custom_fields=[]
2015-03-11 07:43:33,137 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readCustomFields readCustomFields(
2015-03-11 07:43:33,139 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/custom_fields.json
2015-03-11 07:43:33,140 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,141 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,141 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/custom_fields.json #-1
2015-03-11 07:43:33,142 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,143 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@7516d0de6 pairs: {GET /custom_fields.json HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,153 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@394725e812 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "331aba81fea1e8dcbcb1b1a117050169"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 57fe19564e0c8e9fe8cad108a6e24726}{X-Runtime: 0.000000}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 20}
2015-03-11 07:43:33,154 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,154 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,155 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,155 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,155 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,156 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[57fe19564e0c8e9fe8cad108a6e24726]
2015-03-11 07:43:33,156 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["331aba81fea1e8dcbcb1b1a117050169"]
2015-03-11 07:43:33,157 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.000000]
2015-03-11 07:43:33,157 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[20]
2015-03-11 07:43:33,157 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,158 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,158 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,159 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,159 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,160 INFO com.wilutions.itol.db.HttpClient send 200 #20
2015-03-11 07:43:33,160 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 57fe19564e0c8e9fe8cad108a6e24726, ETag: "331aba81fea1e8dcbcb1b1a117050169", X-Runtime: 0.000000, Content-Length: 20, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"custom_fields":[]}]
2015-03-11 07:43:33,163 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump response={
  "custom_fields": []
}
2015-03-11 07:43:33,167 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readCustomFields )readCustomFields
2015-03-11 07:43:33,177 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump update configProject={
  "id": 20,
  "name": "Issue Tracker for Microsoft Outlook and Redmine Configuration",
  "identifier": "itol-configuration",
  "description": "This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:43:33.172Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>",
  "homepage": "",
  "status": 1,
  "is_public": true,
  "created_on": "2015-03-10T15:18:25Z",
  "updated_on": "2015-03-11T07:29:23Z"
}
2015-03-11 07:43:33,182 FINE com.wilutions.itol.db.HttpClient send send(PUT, url=http://<server>:<port>/projects/itol-configuration.json
2015-03-11 07:43:33,183 FINE com.wilutions.itol.db.HttpClient send headers=[Content-Type: application/json, X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,184 FINE com.wilutions.itol.db.HttpClient send content={"project":{"id":20,"name":"Issue Tracker for Microsoft Outlook and Redmine Configuration","identifier":"itol-configuration","description":"This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:43:33.172Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>","homepage":"","status":1,"is_public":true,"created_on":"2015-03-10T15:18:25Z","updated_on":"2015-03-11T07:29:23Z"}}
2015-03-11 07:43:33,184 INFO com.wilutions.itol.db.HttpClient send PUT http://<server>:<port>/projects/itol-configuration.json #-1
2015-03-11 07:43:33,185 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@f7b9d8210 pairs: {PUT /projects/itol-configuration.json HTTP/1.1: null}{Content-Type: application/json}{X-Redmine-API-Key: <api key>}{Cache-Control: no-cache}{Pragma: no-cache}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}{Transfer-Encoding: chunked}
2015-03-11 07:43:33,187 FINE com.wilutions.itol.db.HttpClient writeStringIntoStream writeStringIntoStream(length=506
2015-03-11 07:43:33,188 FINE com.wilutions.itol.db.HttpClient writeStringIntoStream )writeStringIntoStream
2015-03-11 07:43:33,189 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,308 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@75c737111 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: no-cache}{Content-Type: application/json; charset=utf-8}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: invalidate, pass}{X-Request-Id: 2575f3834b7e45f225c77fa716357813}{X-Runtime: 0.124801}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 0}
2015-03-11 07:43:33,309 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,309 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,310 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,310 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,310 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[no-cache]
2015-03-11 07:43:33,310 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[2575f3834b7e45f225c77fa716357813]
2015-03-11 07:43:33,311 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.124801]
2015-03-11 07:43:33,311 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[0]
2015-03-11 07:43:33,311 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,311 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[invalidate, pass]
2015-03-11 07:43:33,311 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,312 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,312 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,312 INFO com.wilutions.itol.db.HttpClient send 200 #0
2015-03-11 07:43:33,312 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: no-cache, X-Request-Id: 2575f3834b7e45f225c77fa716357813, X-Runtime: 0.124801, Content-Length: 0, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: invalidate, pass, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content=]
2015-03-11 07:43:33,314 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readOrUpdateConfigurationProject data.isAdmin=true
2015-03-11 07:43:33,315 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readOrUpdateConfigurationProject )readOrUpdateConfigurationProject
2015-03-11 07:43:33,316 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readProjects
2015-03-11 07:43:33,317 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects readProjects(
2015-03-11 07:43:33,318 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/projects.json?include=trackers,issue_categories,enabled_modules&offset=0&limit=100
2015-03-11 07:43:33,319 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,319 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,319 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/projects.json?include=trackers,issue_categories,enabled_modules&offset=0&limit=100 #-1
2015-03-11 07:43:33,319 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,320 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@64faa7836 pairs: {GET /projects.json?include=trackers,issue_categories,enabled_modules&offset=0&limit=100 HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,459 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@373ea11312 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "e858cddf8aa187012566df7c7cf375b4"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 8c300e6067944bf19d29bd919b38b33d}{X-Runtime: 0.140402}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 12922}
2015-03-11 07:43:33,460 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,461 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,461 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,461 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,462 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,462 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[8c300e6067944bf19d29bd919b38b33d]
2015-03-11 07:43:33,464 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["e858cddf8aa187012566df7c7cf375b4"]
2015-03-11 07:43:33,464 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.140402]
2015-03-11 07:43:33,464 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[12922]
2015-03-11 07:43:33,464 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,464 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,468 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,469 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,469 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,469 INFO com.wilutions.itol.db.HttpClient send 200 #12922
2015-03-11 07:43:33,470 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 8c300e6067944bf19d29bd919b38b33d, ETag: "e858cddf8aa187012566df7c7cf375b4", X-Runtime: 0.140402, Content-Length: 12922, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"projects":[{"id":3,"name":"DYS0001","identifier":"dys0001","description":"XXX EOL Test","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":21,"name":"issue_tracking"},{"id":22,"name":"time_tracking"},{"id":23,"name":"news"},{"id":24,"name":"documents"},{"id":25,"name":"files"},{"id":26,"name":"wiki"},{"id":27,"name":"repository"},{"id":28,"name":"boards"},{"id":29,"name":"calendar"},{"id":30,"name":"gantt"}],"created_on":"2014-09-16T07:28:17Z","updated_on":"2014-09-16T07:28:17Z"},{"id":4,"name":"Import CSV Serial Number Data","identifier":"import-csv-serial-number-data","description":"Application to automate importing of serial number and certificate data","parent":{"id":3,"name":"DYS0001"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":31,"name":"issue_tracking"},{"id":32,"name":"time_tracking"},{"id":33,"name":"news"},{"id":34,"name":"documents"},{"id":35,"name":"files"},{"id":36,"name":"wiki"},{"id":37,"name":"repository"},{"id":38,"name":"boards"},{"id":39,"name":"calendar"},{"id":40,"name":"gantt"},{"id":188,"name":"agile"}],"created_on":"2014-09-16T07:29:13Z","updated_on":"2014-09-17T07:07:16Z"},{"id":20,"name":"Issue Tracker for Microsoft Outlook and Redmine Configuration","identifier":"itol-configuration","description":"This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:43:33.172Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"}],"issue_categories":[],"enabled_modules":[],"created_on":"2015-03-10T15:18:25Z","updated_on":"2015-03-11T07:43:33Z"},{"id":16,"name":"XXX0419","identifier":"XXX0419","description":"XXX ETS","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"}],"issue_categories":[],"enabled_modules":[{"id":155,"name":"issue_tracking"},{"id":156,"name":"time_tracking"},{"id":157,"name":"news"},{"id":158,"name":"documents"},{"id":159,"name":"files"},{"id":160,"name":"wiki"},{"id":161,"name":"repository"},{"id":162,"name":"boards"},{"id":163,"name":"calendar"},{"id":164,"name":"gantt"},{"id":165,"name":"todos_module"}],"created_on":"2014-12-23T10:02:57Z","updated_on":"2014-12-23T10:02:57Z"},{"id":17,"name":"XXX0431","identifier":"XXX0431","description":"NGSM for XXX","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":166,"name":"issue_tracking"},{"id":167,"name":"time_tracking"},{"id":168,"name":"news"},{"id":169,"name":"documents"},{"id":170,"name":"files"},{"id":171,"name":"wiki"},{"id":172,"name":"repository"},{"id":173,"name":"boards"},{"id":174,"name":"calendar"},{"id":175,"name":"gantt"},{"id":176,"name":"todos_module"}],"created_on":"2014-12-23T10:03:37Z","updated_on":"2014-12-23T10:07:31Z"},{"id":18,"name":"XXX0439","identifier":"XXX0439","description":"Retries for Rears","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":177,"name":"issue_tracking"},{"id":178,"name":"time_tracking"},{"id":179,"name":"news"},{"id":180,"name":"documents"},{"id":181,"name":"files"},{"id":182,"name":"wiki"},{"id":183,"name":"repository"},{"id":184,"name":"boards"},{"id":185,"name":"calendar"},{"id":186,"name":"gantt"},{"id":187,"name":"todos_module"}],"created_on":"2014-12-23T10:04:08Z","updated_on":"2014-12-23T10:06:33Z"},{"id":15,"name":"MAG0491.17","identifier":"mag0491-17","description":"LSM Testers","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":144,"name":"issue_tracking"},{"id":145,"name":"time_tracking"},{"id":146,"name":"news"},{"id":147,"name":"documents"},{"id":148,"name":"files"},{"id":149,"name":"wiki"},{"id":150,"name":"repository"},{"id":151,"name":"boards"},{"id":152,"name":"calendar"},{"id":153,"name":"gantt"},{"id":154,"name":"todos_module"}],"created_on":"2014-12-23T10:02:35Z","updated_on":"2014-12-23T10:02:35Z"},{"id":1,"name":"MAG0506","identifier":"mag0506","description":"XXX Build System","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":1,"name":"issue_tracking"},{"id":2,"name":"time_tracking"},{"id":3,"name":"news"},{"id":4,"name":"documents"},{"id":5,"name":"files"},{"id":6,"name":"wiki"},{"id":7,"name":"repository"},{"id":8,"name":"boards"},{"id":9,"name":"calendar"},{"id":10,"name":"gantt"}],"created_on":"2014-09-16T07:22:56Z","updated_on":"2014-09-16T07:22:56Z"},{"id":2,"name":"JLR Web Service","identifier":"jlr-web-service","description":"Service application to read XML data from JLR web service and add to message queue on SQL Server.","parent":{"id":1,"name":"MAG0506"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":11,"name":"issue_tracking"},{"id":12,"name":"time_tracking"},{"id":13,"name":"news"},{"id":14,"name":"documents"},{"id":15,"name":"files"},{"id":16,"name":"wiki"},{"id":17,"name":"repository"},{"id":18,"name":"boards"},{"id":19,"name":"calendar"},{"id":20,"name":"gantt"}],"created_on":"2014-09-16T07:24:13Z","updated_on":"2014-09-16T07:24:13Z"},{"id":14,"name":"MAG0507","identifier":"mag0507","description":"XXX Cockpit and Console Build and Electrical Test Systems","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":133,"name":"issue_tracking"},{"id":134,"name":"time_tracking"},{"id":135,"name":"news"},{"id":136,"name":"documents"},{"id":137,"name":"files"},{"id":138,"name":"wiki"},{"id":139,"name":"repository"},{"id":140,"name":"boards"},{"id":141,"name":"calendar"},{"id":142,"name":"gantt"},{"id":143,"name":"todos_module"}],"created_on":"2014-12-23T09:59:27Z","updated_on":"2014-12-23T09:59:27Z"},{"id":7,"name":"Network Hardware/Software","identifier":"network","description":"","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":61,"name":"issue_tracking"},{"id":62,"name":"time_tracking"},{"id":63,"name":"news"},{"id":64,"name":"documents"},{"id":65,"name":"files"},{"id":66,"name":"wiki"},{"id":67,"name":"repository"},{"id":68,"name":"boards"},{"id":69,"name":"calendar"},{"id":70,"name":"gantt"},{"id":132,"name":"todos_module"}],"created_on":"2014-09-17T13:08:13Z","updated_on":"2014-09-17T13:08:13Z"},{"id":5,"name":"Mail","identifier":"Mail","description":"Mail configuration and traceability.","parent":{"id":7,"name":"Network Hardware/Software"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":41,"name":"issue_tracking"},{"id":42,"name":"time_tracking"},{"id":43,"name":"news"},{"id":44,"name":"documents"},{"id":45,"name":"files"},{"id":46,"name":"wiki"},{"id":47,"name":"repository"},{"id":48,"name":"boards"},{"id":49,"name":"calendar"},{"id":50,"name":"gantt"}],"created_on":"2014-09-17T12:47:42Z","updated_on":"2014-09-17T12:47:42Z"},{"id":6,"name":"Content Filters","identifier":"Mail-cf","description":"Content filter configuration","parent":{"id":5,"name":"Mail"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":51,"name":"issue_tracking"},{"id":52,"name":"time_tracking"},{"id":53,"name":"news"},{"id":54,"name":"documents"},{"id":55,"name":"files"},{"id":56,"name":"wiki"},{"id":57,"name":"repository"},{"id":58,"name":"boards"},{"id":59,"name":"calendar"},{"id":60,"name":"gantt"}],"created_on":"2014-09-17T12:48:15Z","updated_on":"2014-09-17T12:48:15Z"},{"id":11,"name":"Redmine","identifier":"redmine","description":"","parent":{"id":7,"name":"Network Hardware/Software"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":101,"name":"issue_tracking"},{"id":102,"name":"time_tracking"},{"id":103,"name":"news"},{"id":104,"name":"documents"},{"id":105,"name":"files"},{"id":106,"name":"wiki"},{"id":107,"name":"repository"},{"id":108,"name":"boards"},{"id":109,"name":"calendar"},{"id":110,"name":"gantt"}],"created_on":"2014-09-18T10:28:35Z","updated_on":"2014-09-18T10:28:35Z"},{"id":12,"name":"Plugins","identifier":"redmine-plugins","description":"","parent":{"id":11,"name":"Redmine"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":111,"name":"issue_tracking"},{"id":112,"name":"time_tracking"},{"id":113,"name":"news"},{"id":114,"name":"documents"},{"id":115,"name":"files"},{"id":116,"name":"wiki"},{"id":117,"name":"repository"},{"id":118,"name":"boards"},{"id":119,"name":"calendar"},{"id":120,"name":"gantt"},{"id":131,"name":"todos_module"}],"created_on":"2014-09-18T10:29:03Z","updated_on":"2014-09-18T10:29:03Z"},{"id":13,"name":"Repositories","identifier":"redmine-repositories","description":"Repository configuration for integration with Redmine","parent":{"id":11,"name":"Redmine"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":121,"name":"issue_tracking"},{"id":122,"name":"time_tracking"},{"id":123,"name":"news"},{"id":124,"name":"documents"},{"id":125,"name":"files"},{"id":126,"name":"wiki"},{"id":127,"name":"repository"},{"id":128,"name":"boards"},{"id":129,"name":"calendar"},{"id":130,"name":"gantt"}],"created_on":"2014-09-18T11:05:01Z","updated_on":"2014-09-18T11:05:01Z"},{"id":8,"name":"Software Development","identifier":"softwaredev","description":"Internal and external development projects not directly related to customer projects.","status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":71,"name":"issue_tracking"},{"id":72,"name":"time_tracking"},{"id":73,"name":"news"},{"id":74,"name":"documents"},{"id":75,"name":"files"},{"id":76,"name":"wiki"},{"id":77,"name":"repository"},{"id":78,"name":"boards"},{"id":79,"name":"calendar"},{"id":80,"name":"gantt"}],"created_on":"2014-09-17T14:27:30Z","updated_on":"2014-09-17T14:27:30Z"},{"id":9,"name":"VB6 Components","identifier":"softwarede-vb6ax","description":"Visual Basic 6 ActiveX component development","parent":{"id":8,"name":"Software Development"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":81,"name":"issue_tracking"},{"id":82,"name":"time_tracking"},{"id":83,"name":"news"},{"id":84,"name":"documents"},{"id":85,"name":"files"},{"id":86,"name":"wiki"},{"id":87,"name":"repository"},{"id":88,"name":"boards"},{"id":89,"name":"calendar"},{"id":90,"name":"gantt"}],"created_on":"2014-09-17T14:28:12Z","updated_on":"2014-09-17T14:28:12Z"},{"id":10,"name":"Animated GIF Control","identifier":"softwaredev-vb6_ocx_animatedgif","description":"OCX control for displaying animated GIFs.","parent":{"id":9,"name":"VB6 Components"},"status":1,"is_public":true,"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}],"issue_categories":[],"enabled_modules":[{"id":91,"name":"issue_tracking"},{"id":92,"name":"time_tracking"},{"id":93,"name":"news"},{"id":94,"name":"documents"},{"id":95,"name":"files"},{"id":96,"name":"wiki"},{"id":97,"name":"repository"},{"id":98,"name":"boards"},{"id":99,"name":"calendar"},{"id":100,"name":"gantt"}],"created_on":"2014-09-17T14:29:08Z","updated_on":"2014-09-17T14:29:08Z"}],"total_count":19,"offset":0,"limit":100}]
2015-03-11 07:43:33,506 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 3,
  "name": "DYS0001",
  "identifier": "dys0001",
  "description": "XXX EOL Test",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 21,
      "name": "issue_tracking"
    },
    {
      "id": 22,
      "name": "time_tracking"
    },
    {
      "id": 23,
      "name": "news"
    },
    {
      "id": 24,
      "name": "documents"
    },
    {
      "id": 25,
      "name": "files"
    },
    {
      "id": 26,
      "name": "wiki"
    },
    {
      "id": 27,
      "name": "repository"
    },
    {
      "id": 28,
      "name": "boards"
    },
    {
      "id": 29,
      "name": "calendar"
    },
    {
      "id": 30,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-16T07:28:17Z",
  "updated_on": "2014-09-16T07:28:17Z"
}
2015-03-11 07:43:33,513 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=3, .name=DYS0001
2015-03-11 07:43:33,517 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 4,
  "name": "Import CSV Serial Number Data",
  "identifier": "import-csv-serial-number-data",
  "description": "Application to automate importing of serial number and certificate data",
  "parent": {
    "id": 3,
    "name": "DYS0001"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 31,
      "name": "issue_tracking"
    },
    {
      "id": 32,
      "name": "time_tracking"
    },
    {
      "id": 33,
      "name": "news"
    },
    {
      "id": 34,
      "name": "documents"
    },
    {
      "id": 35,
      "name": "files"
    },
    {
      "id": 36,
      "name": "wiki"
    },
    {
      "id": 37,
      "name": "repository"
    },
    {
      "id": 38,
      "name": "boards"
    },
    {
      "id": 39,
      "name": "calendar"
    },
    {
      "id": 40,
      "name": "gantt"
    },
    {
      "id": 188,
      "name": "agile"
    }
  ],
  "created_on": "2014-09-16T07:29:13Z",
  "updated_on": "2014-09-17T07:07:16Z"
}
2015-03-11 07:43:33,518 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=4, .name=Import CSV Serial Number Data
2015-03-11 07:43:33,521 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 20,
  "name": "Issue Tracker for Microsoft Outlook and Redmine Configuration",
  "identifier": "itol-configuration",
  "description": "This project stores the configuration data for the Issue Tracker Addin for Microsoft Outlook and Redmine. Last update was at: 2015-03-11T07:43:33.172Z. <pre>ENCRYPTED_DATA_BEGIN\nCun4X+g6563TveHvMxQDAwxlkLgUH4k6UOxvDrddlj8=\nENCRYPTED_DATA_END</pre>",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [],
  "created_on": "2015-03-10T15:18:25Z",
  "updated_on": "2015-03-11T07:43:33Z"
}
2015-03-11 07:43:33,522 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=20, .name=Issue Tracker for Microsoft Outlook and Redmine Configuration
2015-03-11 07:43:33,524 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 16,
  "name": "XXX0419",
  "identifier": "XXX0419",
  "description": "XXX ETS",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 155,
      "name": "issue_tracking"
    },
    {
      "id": 156,
      "name": "time_tracking"
    },
    {
      "id": 157,
      "name": "news"
    },
    {
      "id": 158,
      "name": "documents"
    },
    {
      "id": 159,
      "name": "files"
    },
    {
      "id": 160,
      "name": "wiki"
    },
    {
      "id": 161,
      "name": "repository"
    },
    {
      "id": 162,
      "name": "boards"
    },
    {
      "id": 163,
      "name": "calendar"
    },
    {
      "id": 164,
      "name": "gantt"
    },
    {
      "id": 165,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-12-23T10:02:57Z",
  "updated_on": "2014-12-23T10:02:57Z"
}
2015-03-11 07:43:33,525 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=16, .name=XXX0419
2015-03-11 07:43:33,532 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 17,
  "name": "XXX0431",
  "identifier": "XXX0431",
  "description": "NGSM",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 166,
      "name": "issue_tracking"
    },
    {
      "id": 167,
      "name": "time_tracking"
    },
    {
      "id": 168,
      "name": "news"
    },
    {
      "id": 169,
      "name": "documents"
    },
    {
      "id": 170,
      "name": "files"
    },
    {
      "id": 171,
      "name": "wiki"
    },
    {
      "id": 172,
      "name": "repository"
    },
    {
      "id": 173,
      "name": "boards"
    },
    {
      "id": 174,
      "name": "calendar"
    },
    {
      "id": 175,
      "name": "gantt"
    },
    {
      "id": 176,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-12-23T10:03:37Z",
  "updated_on": "2014-12-23T10:07:31Z"
}
2015-03-11 07:43:33,533 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=17, .name=XXX0431
2015-03-11 07:43:33,535 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 18,
  "name": "XXX0439",
  "identifier": "XXX0439",
  "description": "Retries for Rears",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 177,
      "name": "issue_tracking"
    },
    {
      "id": 178,
      "name": "time_tracking"
    },
    {
      "id": 179,
      "name": "news"
    },
    {
      "id": 180,
      "name": "documents"
    },
    {
      "id": 181,
      "name": "files"
    },
    {
      "id": 182,
      "name": "wiki"
    },
    {
      "id": 183,
      "name": "repository"
    },
    {
      "id": 184,
      "name": "boards"
    },
    {
      "id": 185,
      "name": "calendar"
    },
    {
      "id": 186,
      "name": "gantt"
    },
    {
      "id": 187,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-12-23T10:04:08Z",
  "updated_on": "2014-12-23T10:06:33Z"
}
2015-03-11 07:43:33,536 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=18, .name=XXX0439
2015-03-11 07:43:33,538 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 15,
  "name": "MAG0491.17",
  "identifier": "mag0491-17",
  "description": "LSM Testers",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 144,
      "name": "issue_tracking"
    },
    {
      "id": 145,
      "name": "time_tracking"
    },
    {
      "id": 146,
      "name": "news"
    },
    {
      "id": 147,
      "name": "documents"
    },
    {
      "id": 148,
      "name": "files"
    },
    {
      "id": 149,
      "name": "wiki"
    },
    {
      "id": 150,
      "name": "repository"
    },
    {
      "id": 151,
      "name": "boards"
    },
    {
      "id": 152,
      "name": "calendar"
    },
    {
      "id": 153,
      "name": "gantt"
    },
    {
      "id": 154,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-12-23T10:02:35Z",
  "updated_on": "2014-12-23T10:02:35Z"
}
2015-03-11 07:43:33,540 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=15, .name=MAG0491.17
2015-03-11 07:43:33,542 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 1,
  "name": "MAG0506",
  "identifier": "mag0506",
  "description": "XXX Build System",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 1,
      "name": "issue_tracking"
    },
    {
      "id": 2,
      "name": "time_tracking"
    },
    {
      "id": 3,
      "name": "news"
    },
    {
      "id": 4,
      "name": "documents"
    },
    {
      "id": 5,
      "name": "files"
    },
    {
      "id": 6,
      "name": "wiki"
    },
    {
      "id": 7,
      "name": "repository"
    },
    {
      "id": 8,
      "name": "boards"
    },
    {
      "id": 9,
      "name": "calendar"
    },
    {
      "id": 10,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-16T07:22:56Z",
  "updated_on": "2014-09-16T07:22:56Z"
}
2015-03-11 07:43:33,543 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=1, .name=MAG0506
2015-03-11 07:43:33,545 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 2,
  "name": "JLR Web Service",
  "identifier": "jlr-web-service",
  "description": "Service application to read XML data from JLR web service and add to message queue on SQL Server.",
  "parent": {
    "id": 1,
    "name": "MAG0506"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 11,
      "name": "issue_tracking"
    },
    {
      "id": 12,
      "name": "time_tracking"
    },
    {
      "id": 13,
      "name": "news"
    },
    {
      "id": 14,
      "name": "documents"
    },
    {
      "id": 15,
      "name": "files"
    },
    {
      "id": 16,
      "name": "wiki"
    },
    {
      "id": 17,
      "name": "repository"
    },
    {
      "id": 18,
      "name": "boards"
    },
    {
      "id": 19,
      "name": "calendar"
    },
    {
      "id": 20,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-16T07:24:13Z",
  "updated_on": "2014-09-16T07:24:13Z"
}
2015-03-11 07:43:33,546 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=2, .name=JLR Web Service
2015-03-11 07:43:33,548 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 14,
  "name": "MAG0507",
  "identifier": "mag0507",
  "description": "XXX Cockpit and Console Build and Electrical Test Systems",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 133,
      "name": "issue_tracking"
    },
    {
      "id": 134,
      "name": "time_tracking"
    },
    {
      "id": 135,
      "name": "news"
    },
    {
      "id": 136,
      "name": "documents"
    },
    {
      "id": 137,
      "name": "files"
    },
    {
      "id": 138,
      "name": "wiki"
    },
    {
      "id": 139,
      "name": "repository"
    },
    {
      "id": 140,
      "name": "boards"
    },
    {
      "id": 141,
      "name": "calendar"
    },
    {
      "id": 142,
      "name": "gantt"
    },
    {
      "id": 143,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-12-23T09:59:27Z",
  "updated_on": "2014-12-23T09:59:27Z"
}
2015-03-11 07:43:33,549 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=14, .name=MAG0507
2015-03-11 07:43:33,551 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 7,
  "name": "Network Hardware/Software",
  "identifier": "network",
  "description": "",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 61,
      "name": "issue_tracking"
    },
    {
      "id": 62,
      "name": "time_tracking"
    },
    {
      "id": 63,
      "name": "news"
    },
    {
      "id": 64,
      "name": "documents"
    },
    {
      "id": 65,
      "name": "files"
    },
    {
      "id": 66,
      "name": "wiki"
    },
    {
      "id": 67,
      "name": "repository"
    },
    {
      "id": 68,
      "name": "boards"
    },
    {
      "id": 69,
      "name": "calendar"
    },
    {
      "id": 70,
      "name": "gantt"
    },
    {
      "id": 132,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-09-17T13:08:13Z",
  "updated_on": "2014-09-17T13:08:13Z"
}
2015-03-11 07:43:33,552 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=7, .name=Network Hardware/Software
2015-03-11 07:43:33,554 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 5,
  "name": "Mail",
  "identifier": "Mail",
  "description": "Mail configuration and traceability.",
  "parent": {
    "id": 7,
    "name": "Network Hardware/Software"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 41,
      "name": "issue_tracking"
    },
    {
      "id": 42,
      "name": "time_tracking"
    },
    {
      "id": 43,
      "name": "news"
    },
    {
      "id": 44,
      "name": "documents"
    },
    {
      "id": 45,
      "name": "files"
    },
    {
      "id": 46,
      "name": "wiki"
    },
    {
      "id": 47,
      "name": "repository"
    },
    {
      "id": 48,
      "name": "boards"
    },
    {
      "id": 49,
      "name": "calendar"
    },
    {
      "id": 50,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-17T12:47:42Z",
  "updated_on": "2014-09-17T12:47:42Z"
}
2015-03-11 07:43:33,555 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=5, .name=Mail
2015-03-11 07:43:33,558 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 6,
  "name": "Content Filters",
  "identifier": "Mail-cf",
  "description": "Content filter configuration",
  "parent": {
    "id": 5,
    "name": "Mail"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 51,
      "name": "issue_tracking"
    },
    {
      "id": 52,
      "name": "time_tracking"
    },
    {
      "id": 53,
      "name": "news"
    },
    {
      "id": 54,
      "name": "documents"
    },
    {
      "id": 55,
      "name": "files"
    },
    {
      "id": 56,
      "name": "wiki"
    },
    {
      "id": 57,
      "name": "repository"
    },
    {
      "id": 58,
      "name": "boards"
    },
    {
      "id": 59,
      "name": "calendar"
    },
    {
      "id": 60,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-17T12:48:15Z",
  "updated_on": "2014-09-17T12:48:15Z"
}
2015-03-11 07:43:33,559 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=6, .name=Content Filters
2015-03-11 07:43:33,561 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 11,
  "name": "Redmine",
  "identifier": "redmine",
  "description": "",
  "parent": {
    "id": 7,
    "name": "Network Hardware/Software"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 101,
      "name": "issue_tracking"
    },
    {
      "id": 102,
      "name": "time_tracking"
    },
    {
      "id": 103,
      "name": "news"
    },
    {
      "id": 104,
      "name": "documents"
    },
    {
      "id": 105,
      "name": "files"
    },
    {
      "id": 106,
      "name": "wiki"
    },
    {
      "id": 107,
      "name": "repository"
    },
    {
      "id": 108,
      "name": "boards"
    },
    {
      "id": 109,
      "name": "calendar"
    },
    {
      "id": 110,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-18T10:28:35Z",
  "updated_on": "2014-09-18T10:28:35Z"
}
2015-03-11 07:43:33,561 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=11, .name=Redmine
2015-03-11 07:43:33,563 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 12,
  "name": "Plugins",
  "identifier": "redmine-plugins",
  "description": "",
  "parent": {
    "id": 11,
    "name": "Redmine"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 111,
      "name": "issue_tracking"
    },
    {
      "id": 112,
      "name": "time_tracking"
    },
    {
      "id": 113,
      "name": "news"
    },
    {
      "id": 114,
      "name": "documents"
    },
    {
      "id": 115,
      "name": "files"
    },
    {
      "id": 116,
      "name": "wiki"
    },
    {
      "id": 117,
      "name": "repository"
    },
    {
      "id": 118,
      "name": "boards"
    },
    {
      "id": 119,
      "name": "calendar"
    },
    {
      "id": 120,
      "name": "gantt"
    },
    {
      "id": 131,
      "name": "todos_module"
    }
  ],
  "created_on": "2014-09-18T10:29:03Z",
  "updated_on": "2014-09-18T10:29:03Z"
}
2015-03-11 07:43:33,563 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=12, .name=Plugins
2015-03-11 07:43:33,566 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 13,
  "name": "Repositories",
  "identifier": "redmine-repositories",
  "description": "Repository configuration for integration with Redmine",
  "parent": {
    "id": 11,
    "name": "Redmine"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 121,
      "name": "issue_tracking"
    },
    {
      "id": 122,
      "name": "time_tracking"
    },
    {
      "id": 123,
      "name": "news"
    },
    {
      "id": 124,
      "name": "documents"
    },
    {
      "id": 125,
      "name": "files"
    },
    {
      "id": 126,
      "name": "wiki"
    },
    {
      "id": 127,
      "name": "repository"
    },
    {
      "id": 128,
      "name": "boards"
    },
    {
      "id": 129,
      "name": "calendar"
    },
    {
      "id": 130,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-18T11:05:01Z",
  "updated_on": "2014-09-18T11:05:01Z"
}
2015-03-11 07:43:33,566 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=13, .name=Repositories
2015-03-11 07:43:33,568 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 8,
  "name": "Software Development",
  "identifier": "softwaredev",
  "description": "Internal and external development projects not directly related to customer projects.",
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 71,
      "name": "issue_tracking"
    },
    {
      "id": 72,
      "name": "time_tracking"
    },
    {
      "id": 73,
      "name": "news"
    },
    {
      "id": 74,
      "name": "documents"
    },
    {
      "id": 75,
      "name": "files"
    },
    {
      "id": 76,
      "name": "wiki"
    },
    {
      "id": 77,
      "name": "repository"
    },
    {
      "id": 78,
      "name": "boards"
    },
    {
      "id": 79,
      "name": "calendar"
    },
    {
      "id": 80,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-17T14:27:30Z",
  "updated_on": "2014-09-17T14:27:30Z"
}
2015-03-11 07:43:33,568 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=8, .name=Software Development
2015-03-11 07:43:33,570 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 9,
  "name": "VB6 Components",
  "identifier": "softwarede-vb6ax",
  "description": "Visual Basic 6 ActiveX component development",
  "parent": {
    "id": 8,
    "name": "Software Development"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 81,
      "name": "issue_tracking"
    },
    {
      "id": 82,
      "name": "time_tracking"
    },
    {
      "id": 83,
      "name": "news"
    },
    {
      "id": 84,
      "name": "documents"
    },
    {
      "id": 85,
      "name": "files"
    },
    {
      "id": 86,
      "name": "wiki"
    },
    {
      "id": 87,
      "name": "repository"
    },
    {
      "id": 88,
      "name": "boards"
    },
    {
      "id": 89,
      "name": "calendar"
    },
    {
      "id": 90,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-17T14:28:12Z",
  "updated_on": "2014-09-17T14:28:12Z"
}
2015-03-11 07:43:33,570 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=9, .name=VB6 Components
2015-03-11 07:43:33,574 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump project={
  "id": 10,
  "name": "Animated GIF Control",
  "identifier": "softwaredev-vb6_ocx_animatedgif",
  "description": "OCX control for displaying animated GIFs.",
  "parent": {
    "id": 9,
    "name": "VB6 Components"
  },
  "status": 1,
  "is_public": true,
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ],
  "issue_categories": [],
  "enabled_modules": [
    {
      "id": 91,
      "name": "issue_tracking"
    },
    {
      "id": 92,
      "name": "time_tracking"
    },
    {
      "id": 93,
      "name": "news"
    },
    {
      "id": 94,
      "name": "documents"
    },
    {
      "id": 95,
      "name": "files"
    },
    {
      "id": 96,
      "name": "wiki"
    },
    {
      "id": 97,
      "name": "repository"
    },
    {
      "id": 98,
      "name": "boards"
    },
    {
      "id": 99,
      "name": "calendar"
    },
    {
      "id": 100,
      "name": "gantt"
    }
  ],
  "created_on": "2014-09-17T14:29:08Z",
  "updated_on": "2014-09-17T14:29:08Z"
}
2015-03-11 07:43:33,575 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=10, .name=Animated GIF Control
2015-03-11 07:43:33,578 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/projects.json?include=trackers,issue_categories,enabled_modules&offset=19&limit=100
2015-03-11 07:43:33,578 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,578 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,579 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/projects.json?include=trackers,issue_categories,enabled_modules&offset=19&limit=100 #-1
2015-03-11 07:43:33,579 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,580 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@6bb6bb6a6 pairs: {GET /projects.json?include=trackers,issue_categories,enabled_modules&offset=19&limit=100 HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,591 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@6f3c161012 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "8724d67962e7495ddbd76346237c70a2"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 3ffd94a8bce8340e6a9337bebdd29005}{X-Runtime: 0.000000}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 56}
2015-03-11 07:43:33,591 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,592 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,592 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,592 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,592 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,592 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[3ffd94a8bce8340e6a9337bebdd29005]
2015-03-11 07:43:33,593 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["8724d67962e7495ddbd76346237c70a2"]
2015-03-11 07:43:33,593 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.000000]
2015-03-11 07:43:33,593 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[56]
2015-03-11 07:43:33,593 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,593 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,594 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,594 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,594 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,594 INFO com.wilutions.itol.db.HttpClient send 200 #56
2015-03-11 07:43:33,595 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 3ffd94a8bce8340e6a9337bebdd29005, ETag: "8724d67962e7495ddbd76346237c70a2", X-Runtime: 0.000000, Content-Length: 56, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"projects":[],"total_count":19,"offset":19,"limit":100}]
2015-03-11 07:43:33,600 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=1, name=MAG0506
2015-03-11 07:43:33,602 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=2, name=MAG0506 » JLR Web Service
2015-03-11 07:43:33,603 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=3, name=DYS0001
2015-03-11 07:43:33,606 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=4, name=DYS0001 » Import CSV Serial Number Data
2015-03-11 07:43:33,607 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=5, name=Network Hardware/Software » Mail
2015-03-11 07:43:33,610 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=6, name=Network Hardware/Software » Mail » Content Filters
2015-03-11 07:43:33,612 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=7, name=Network Hardware/Software
2015-03-11 07:43:33,613 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=8, name=Software Development
2015-03-11 07:43:33,617 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=9, name=Software Development » VB6 Components
2015-03-11 07:43:33,618 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=10, name=Software Development » VB6 Components » Animated GIF Control
2015-03-11 07:43:33,620 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=11, name=Network Hardware/Software » Redmine
2015-03-11 07:43:33,621 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=12, name=Network Hardware/Software » Redmine » Plugins
2015-03-11 07:43:33,622 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=13, name=Network Hardware/Software » Redmine » Repositories
2015-03-11 07:43:33,623 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=14, name=MAG0507
2015-03-11 07:43:33,623 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=15, name=MAG0491.17
2015-03-11 07:43:33,624 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=16, name=XXX0419
2015-03-11 07:43:33,624 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=17, name=XXX0431
2015-03-11 07:43:33,625 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=18, name=XXX0439
2015-03-11 07:43:33,625 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects project.id=20, name=Issue Tracker for Microsoft Outlook and Redmine Configuration
2015-03-11 07:43:33,628 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readProjects )readProjects
2015-03-11 07:43:33,630 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readCurrentUser
2015-03-11 07:43:33,632 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readCurrentUser readCurrentUser(
2015-03-11 07:43:33,633 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/users/current.json?include=memberships,groups
2015-03-11 07:43:33,634 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,634 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,634 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/users/current.json?include=memberships,groups #-1
2015-03-11 07:43:33,634 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,635 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@1a6be9eb6 pairs: {GET /users/current.json?include=memberships,groups HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,709 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@3068c84112 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "95bf92f4d7034a28345b1bdde0c8da04"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 48c052c7c285332c30c6fd9612a9a9f0}{X-Runtime: 0.078001}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 978}
2015-03-11 07:43:33,710 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,710 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,710 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,711 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,711 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,711 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[48c052c7c285332c30c6fd9612a9a9f0]
2015-03-11 07:43:33,712 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["95bf92f4d7034a28345b1bdde0c8da04"]
2015-03-11 07:43:33,712 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.078001]
2015-03-11 07:43:33,712 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[978]
2015-03-11 07:43:33,712 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,713 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,713 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,713 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,713 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,714 INFO com.wilutions.itol.db.HttpClient send 200 #978
2015-03-11 07:43:33,714 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 48c052c7c285332c30c6fd9612a9a9f0, ETag: "95bf92f4d7034a28345b1bdde0c8da04", X-Runtime: 0.078001, Content-Length: 978, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"user":{"id":3,"login":"<me>","firstname":"Dan","lastname":"lastname","mail":"<me>@sorion.co.uk","created_on":"2014-09-16T07:21:58Z","last_login_on":"2015-03-11T07:17:11Z","api_key":"<api key>","status":1,"groups":[{"id":4,"name":"Developers"}],"memberships":[{"id":2,"project":{"id":3,"name":"DYS0001"},"roles":[{"id":4,"name":"Developer","inherited":true},{"id":3,"name":"Manager","inherited":true},{"id":5,"name":"Reporter","inherited":true}]},{"id":6,"project":{"id":4,"name":"Import CSV Serial Number Data"},"roles":[{"id":4,"name":"Developer","inherited":true},{"id":3,"name":"Manager","inherited":true},{"id":5,"name":"Reporter","inherited":true}]},{"id":4,"project":{"id":1,"name":"MAG0506"},"roles":[{"id":3,"name":"Manager","inherited":true},{"id":4,"name":"Developer","inherited":true},{"id":5,"name":"Reporter","inherited":true}]},{"id":14,"project":{"id":14,"name":"MAG0507"},"roles":[{"id":4,"name":"Developer"}]}]}}]
2015-03-11 07:43:33,718 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readCurrentUser me.id=3, .name=undefined
2015-03-11 07:43:33,722 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump user ={
  "id": 3,
  "login": "<me>",
  "firstname": "Dan",
  "lastname": "lastname",
  "mail": "<me>@sorion.co.uk",
  "created_on": "2014-09-16T07:21:58Z",
  "last_login_on": "2015-03-11T07:17:11Z",
  "api_key": "<api key>",
  "status": 1,
  "groups": [
    {
      "id": 4,
      "name": "Developers"
    }
  ],
  "memberships": [
    {
      "id": 2,
      "project": {
        "id": 3,
        "name": "DYS0001"
      },
      "roles": [
        {
          "id": 4,
          "name": "Developer",
          "inherited": true
        },
        {
          "id": 3,
          "name": "Manager",
          "inherited": true
        },
        {
          "id": 5,
          "name": "Reporter",
          "inherited": true
        }
      ]
    },
    {
      "id": 6,
      "project": {
        "id": 4,
        "name": "Import CSV Serial Number Data"
      },
      "roles": [
        {
          "id": 4,
          "name": "Developer",
          "inherited": true
        },
        {
          "id": 3,
          "name": "Manager",
          "inherited": true
        },
        {
          "id": 5,
          "name": "Reporter",
          "inherited": true
        }
      ]
    },
    {
      "id": 4,
      "project": {
        "id": 1,
        "name": "MAG0506"
      },
      "roles": [
        {
          "id": 3,
          "name": "Manager",
          "inherited": true
        },
        {
          "id": 4,
          "name": "Developer",
          "inherited": true
        },
        {
          "id": 5,
          "name": "Reporter",
          "inherited": true
        }
      ]
    },
    {
      "id": 14,
      "project": {
        "id": 14,
        "name": "MAG0507"
      },
      "roles": [
        {
          "id": 4,
          "name": "Developer"
        }
      ]
    }
  ]
}
2015-03-11 07:43:33,724 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readCurrentUser )readCurrentUser
2015-03-11 07:43:33,725 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readTrackers
2015-03-11 07:43:33,728 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers readTrackers(
2015-03-11 07:43:33,729 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/trackers.json
2015-03-11 07:43:33,729 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,729 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,729 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/trackers.json #-1
2015-03-11 07:43:33,730 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,730 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@7e51cc0e6 pairs: {GET /trackers.json HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,742 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@49f4f8212 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "372ec1ae5a0addfbe12dce72f3b6ebe6"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: dd0b60f09db4f1bf9cc0dabc75152174}{X-Runtime: 0.015600}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 111}
2015-03-11 07:43:33,742 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,742 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,742 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,743 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,743 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,743 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[dd0b60f09db4f1bf9cc0dabc75152174]
2015-03-11 07:43:33,743 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["372ec1ae5a0addfbe12dce72f3b6ebe6"]
2015-03-11 07:43:33,744 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.015600]
2015-03-11 07:43:33,744 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[111]
2015-03-11 07:43:33,744 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,744 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,744 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,745 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,745 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,745 INFO com.wilutions.itol.db.HttpClient send 200 #111
2015-03-11 07:43:33,746 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: dd0b60f09db4f1bf9cc0dabc75152174, ETag: "372ec1ae5a0addfbe12dce72f3b6ebe6", X-Runtime: 0.015600, Content-Length: 111, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"trackers":[{"id":1,"name":"Bug"},{"id":2,"name":"Feature"},{"id":3,"name":"Support"},{"id":4,"name":"ToDo"}]}]
2015-03-11 07:43:33,747 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump trackersResponse={
  "trackers": [
    {
      "id": 1,
      "name": "Bug"
    },
    {
      "id": 2,
      "name": "Feature"
    },
    {
      "id": 3,
      "name": "Support"
    },
    {
      "id": 4,
      "name": "ToDo"
    }
  ]
}
2015-03-11 07:43:33,749 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers tracker.id=1, .name=Bug
2015-03-11 07:43:33,753 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers tracker.id=2, .name=Feature
2015-03-11 07:43:33,754 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers tracker.id=3, .name=Support
2015-03-11 07:43:33,755 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers tracker.id=4, .name=ToDo
2015-03-11 07:43:33,756 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readTrackers )readTrackers
2015-03-11 07:43:33,758 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readPriorities
2015-03-11 07:43:33,760 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities readPriorities(
2015-03-11 07:43:33,761 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/enumerations/issue_priorities.json
2015-03-11 07:43:33,762 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,762 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,762 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/enumerations/issue_priorities.json #-1
2015-03-11 07:43:33,762 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,763 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@a4bafee6 pairs: {GET /enumerations/issue_priorities.json HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,775 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@6436f44112 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "da960f7f5a223d5cae9de00837ceb41a"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 99d109fe33a6f97e3680ea71bc90c339}{X-Runtime: 0.015600}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 163}
2015-03-11 07:43:33,776 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,776 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,777 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,777 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,777 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,777 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[99d109fe33a6f97e3680ea71bc90c339]
2015-03-11 07:43:33,778 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["da960f7f5a223d5cae9de00837ceb41a"]
2015-03-11 07:43:33,778 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.015600]
2015-03-11 07:43:33,778 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[163]
2015-03-11 07:43:33,778 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,778 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,779 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,779 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,779 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,779 INFO com.wilutions.itol.db.HttpClient send 200 #163
2015-03-11 07:43:33,779 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 99d109fe33a6f97e3680ea71bc90c339, ETag: "da960f7f5a223d5cae9de00837ceb41a", X-Runtime: 0.015600, Content-Length: 163, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"issue_priorities":[{"id":1,"name":"Low"},{"id":2,"name":"Normal","is_default":true},{"id":3,"name":"High"},{"id":4,"name":"Urgent"},{"id":5,"name":"Immediate"}]}]
2015-03-11 07:43:33,781 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump prioritiesResponse={
  "issue_priorities": [
    {
      "id": 1,
      "name": "Low"
    },
    {
      "id": 2,
      "name": "Normal",
      "is_default": true
    },
    {
      "id": 3,
      "name": "High"
    },
    {
      "id": 4,
      "name": "Urgent"
    },
    {
      "id": 5,
      "name": "Immediate"
    }
  ]
}
2015-03-11 07:43:33,784 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities priority.id=1, .name=Low
2015-03-11 07:43:33,786 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities priority.id=2, .name=Normal
2015-03-11 07:43:33,788 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities priority.id=3, .name=High
2015-03-11 07:43:33,789 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities priority.id=4, .name=Urgent
2015-03-11 07:43:33,791 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities priority.id=5, .name=Immediate
2015-03-11 07:43:33,793 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readPriorities )readPriorities
2015-03-11 07:43:33,795 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize readStatuses
2015-03-11 07:43:33,797 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses readStatuses(
2015-03-11 07:43:33,798 FINE com.wilutions.itol.db.HttpClient send send(GET, url=http://<server>:<port>/issue_statuses.json
2015-03-11 07:43:33,798 FINE com.wilutions.itol.db.HttpClient send headers=[X-Redmine-API-Key: <api key>]
2015-03-11 07:43:33,798 FINE com.wilutions.itol.db.HttpClient send content=null
2015-03-11 07:43:33,799 INFO com.wilutions.itol.db.HttpClient send GET http://<server>:<port>/issue_statuses.json #-1
2015-03-11 07:43:33,799 FINE com.wilutions.itol.db.HttpClient send getResponseCode...
2015-03-11 07:43:33,799 FINE sun.net.www.protocol.http.HttpURLConnection writeRequests sun.net.www.MessageHeader@4a4041b46 pairs: {GET /issue_statuses.json HTTP/1.1: null}{X-Redmine-API-Key: <api key>}{User-Agent: Java/1.8.0_31}{Host: <server>:<port>}{Accept: text/html, image/gif, image/jpeg, *; q=.2, */*; q=.2}{Connection: keep-alive}
2015-03-11 07:43:33,810 FINE sun.net.www.protocol.http.HttpURLConnection getInputStream0 sun.net.www.MessageHeader@6ee5b03112 pairs: {null: HTTP/1.1 200 OK}{Cache-Control: max-age=0, private, must-revalidate}{Content-Type: application/json; charset=utf-8}{ETag: "a4435cc62917f86076a6aaedee56d43b"}{Server: Microsoft-IIS/7.5}{X-Rack-Cache: miss}{X-Request-Id: 42750532d472c4502dedb7ebb09d2b22}{X-Runtime: 0.000000}{X-UA-Compatible: IE=Edge,chrome=1}{X-Powered-By: ASP.NET}{Date: Wed, 11 Mar 2015 07:43:33 GMT}{Content-Length: 230}
2015-03-11 07:43:33,810 FINE com.wilutions.itol.db.HttpClient send status=200
2015-03-11 07:43:33,811 FINE com.wilutions.itol.db.HttpClient send response header=null, values=[HTTP/1.1 200 OK]
2015-03-11 07:43:33,811 FINE com.wilutions.itol.db.HttpClient send response header=X-UA-Compatible, values=[IE=Edge,chrome=1]
2015-03-11 07:43:33,811 FINE com.wilutions.itol.db.HttpClient send response header=Server, values=[Microsoft-IIS/7.5]
2015-03-11 07:43:33,811 FINE com.wilutions.itol.db.HttpClient send response header=Cache-Control, values=[max-age=0, private, must-revalidate]
2015-03-11 07:43:33,811 FINE com.wilutions.itol.db.HttpClient send response header=X-Request-Id, values=[42750532d472c4502dedb7ebb09d2b22]
2015-03-11 07:43:33,812 FINE com.wilutions.itol.db.HttpClient send response header=ETag, values=["a4435cc62917f86076a6aaedee56d43b"]
2015-03-11 07:43:33,812 FINE com.wilutions.itol.db.HttpClient send response header=X-Runtime, values=[0.000000]
2015-03-11 07:43:33,812 FINE com.wilutions.itol.db.HttpClient send response header=Content-Length, values=[230]
2015-03-11 07:43:33,812 FINE com.wilutions.itol.db.HttpClient send response header=Date, values=[Wed, 11 Mar 2015 07:43:33 GMT]
2015-03-11 07:43:33,812 FINE com.wilutions.itol.db.HttpClient send response header=X-Rack-Cache, values=[miss]
2015-03-11 07:43:33,813 FINE com.wilutions.itol.db.HttpClient send response header=X-Powered-By, values=[ASP.NET]
2015-03-11 07:43:33,813 FINE com.wilutions.itol.db.HttpClient send response header=Content-Type, values=[application/json; charset=utf-8]
2015-03-11 07:43:33,813 FINE com.wilutions.itol.db.HttpClient send read from input...
2015-03-11 07:43:33,813 INFO com.wilutions.itol.db.HttpClient send 200 #230
2015-03-11 07:43:33,814 FINE com.wilutions.itol.db.HttpClient send )send=200, ret=[status=200,headers=[: HTTP/1.1 200 OK, X-UA-Compatible: IE=Edge,chrome=1, Server: Microsoft-IIS/7.5, Cache-Control: max-age=0, private, must-revalidate, X-Request-Id: 42750532d472c4502dedb7ebb09d2b22, ETag: "a4435cc62917f86076a6aaedee56d43b", X-Runtime: 0.000000, Content-Length: 230, Date: Wed, 11 Mar 2015 07:43:33 GMT, X-Rack-Cache: miss, X-Powered-By: ASP.NET, Content-Type: application/json; charset=utf-8],content={"issue_statuses":[{"id":1,"name":"New","is_default":true},{"id":2,"name":"In Progress"},{"id":3,"name":"Resolved"},{"id":4,"name":"Feedback"},{"id":5,"name":"Closed","is_closed":true},{"id":6,"name":"Rejected","is_closed":true}]}]
2015-03-11 07:43:33,815 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl ddump statusesResponse={
  "issue_statuses": [
    {
      "id": 1,
      "name": "New",
      "is_default": true
    },
    {
      "id": 2,
      "name": "In Progress"
    },
    {
      "id": 3,
      "name": "Resolved"
    },
    {
      "id": 4,
      "name": "Feedback"
    },
    {
      "id": 5,
      "name": "Closed",
      "is_closed": true
    },
    {
      "id": 6,
      "name": "Rejected",
      "is_closed": true
    }
  ]
}
2015-03-11 07:43:33,819 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=1, .name=New
2015-03-11 07:43:33,821 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=2, .name=In Progress
2015-03-11 07:43:33,822 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=3, .name=Resolved
2015-03-11 07:43:33,823 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=4, .name=Feedback
2015-03-11 07:43:33,824 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=5, .name=Closed
2015-03-11 07:43:33,825 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses status.id=6, .name=Rejected
2015-03-11 07:43:33,827 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl readStatuses )readStatuses
2015-03-11 07:43:33,828 INFO jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize initialized
2015-03-11 07:43:33,829 FINE jdk.nashorn.internal.scripts.Script$IssueServiceImpl initialize )initialize
2015-03-11 07:43:33,830 INFO com.wilutions.itol.Globals lambda$0 Issue service initialized.
wolfgangimig commented 9 years ago

Hello Dan, yes, all required JSON data is correctly read. I have to come back to your first post where you already found a problem in registration. I am sorry that I didn't read this carefully enough.

If I install the 64bit setup on Windows7 64bit for Outlook 32bit, I watch exactly the same effects. Would you please double check, whether your Outlook is really 64bit: Start Outlook and open Windows Task Manager. Outlook should be listed as OUTLOOK.EXE and not as OUTLOOK.EXE*32.

Otherwise, please enable further logging which should help us to find the problem. Add the registry keys listed below. Replace the "LogFile" values with an arbitrary file name in an existing directory.

Windows Registry Editor Version 5.00

[HKEY_CURRENT_USER\Software\WILUTIONS\JOA]

[HKEY_CURRENT_USER\Software\WILUTIONS\JOA\JoaCtrl]
"LogFile"="d:\\temp\\log\\joactrl.log"
"LogLevel"="debug"

[HKEY_CURRENT_USER\Software\WILUTIONS\JOA\JoaCtrl\Register]
"LogFile"="d:\\temp\\log\\joa-reg.log"
"LogLevel"="DEBUG"
"LogAppend"="TRUE"

[HKEY_CURRENT_USER\Software\WILUTIONS\JOA\JoaUtilAddin]
"LogFile"="d:\\temp\\log\\joautil.log"
"LogLevel"="debug"

Then, execute in a command window:

cd \Users\...\Issue Tracker for Microsoft Outlook 64bit
"Issue ...exe" /RegisterServer user

The "joa-reg.log" file should be written by this command. Are there any errors inside?

Start Outlook, the other log files should be created, which could grow rather fast. Please send this files to wilutions@googlemail.com.

Best regards Wolfgang

dan-sorion commented 9 years ago

Hi,

I feel like an idiot. I was so convinced I had Outlook 64-bit I didn't check properly. Now have it working in my 32-bit Outlook ... doh! Sorry for opening an issue for this, have now closed it.

Regards,

Dan