Job Completed on Pollination cloud, but not able to load results

mostapha · June 30, 2022, 3:05pm

Hi @mingbo, @jakechevriersg already sent me the logs. There are no errors. You probably need to get on a call with Jake and debug the issue live. I have documented our findings here: https://discourse.pollination.cloud/t/re-job-completed-on-pollination-cloud-but-not-able-to-load-results/1765/6?u=mostapha

mingbo · June 30, 2022, 7:40pm

Hi @jakechevriersg and @mostapha, I have spent some time tracking down this error, and I am able to locate where throws the error message, but I don’t see any issue with it.

I added more logs so that we can get more details about this error. I will let you know when there is a new version is ready for you to test.

mingbo · July 1, 2022, 1:00am

Hi @jakechevriersg, could you please try and test the latest version v1.3.7, and send us the log file when the issue occurs?

jakechevriersg · July 8, 2022, 12:04am

Updated, still no dice… here is a job i uploaded this morning that completed, but component is not registering it as completed

Is it okay to share log file as so??

2022-07-07 16:32:04.822 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-07 16:32:05.006 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.Loader.Share.dll
Pollination.GH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.GH.Loader.gha
Pollination.GH, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.GH.gha
Pollination.Core, Version=1.8.5.0, Culture=neutral, PublicKeyToken=null 1.8.5.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.Core.dll
PollinationSDK, Version=0.23.0.8, Culture=neutral, PublicKeyToken=null 0.23.0.8
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\PollinationSDK.dll
Pollination.UI.Rhino, Version=1.6.37.0, Culture=neutral, PublicKeyToken=null 1.6.37.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.UI.Rhino.dll


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.GH2022070716.txt
2022-07-07 16:35:24.384 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-07 16:35:24.440 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.Loader.Share.dll
Pollination.GH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.GH.Loader.gha
Pollination.GH, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.GH.gha
Pollination.Core, Version=1.8.5.0, Culture=neutral, PublicKeyToken=null 1.8.5.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.Core.dll
PollinationSDK, Version=0.23.0.8, Culture=neutral, PublicKeyToken=null 0.23.0.8
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\PollinationSDK.dll
Pollination.UI.Rhino, Version=1.6.37.0, Culture=neutral, PublicKeyToken=null 1.6.37.0
 - C:\Program Files\pollination\plugin\7.0\Pollination-GH\Pollination.UI.Rhino.dll


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.GH2022070716.txt
2022-07-07 16:49:19.138 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-07 16:49:19.192 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.Loader.Share.dll
Pollination.GH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.GH.Loader.gha
Pollination.GH, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.GH.gha
Pollination.Core, Version=1.8.5.0, Culture=neutral, PublicKeyToken=null 1.8.5.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Core.dll
PollinationSDK, Version=0.23.0.8, Culture=neutral, PublicKeyToken=null 0.23.0.8
 - C:\Program Files\pollination\plugin\7.0\Pollination\PollinationSDK.dll
Pollination.UI.Rhino, Version=1.6.37.0, Culture=neutral, PublicKeyToken=null 1.6.37.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.UI.Rhino.dll


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.GH2022070716.txt
2022-07-07 16:52:15.270 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-07 16:52:15.320 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.Loader.Share.dll
Pollination.GH.Loader, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.4.1\Pollination.GH.Loader.gha
Pollination.GH, Version=1.4.1.0, Culture=neutral, PublicKeyToken=null 1.4.1.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.GH.gha
Pollination.Core, Version=1.8.5.0, Culture=neutral, PublicKeyToken=null 1.8.5.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Core.dll
PollinationSDK, Version=0.23.0.8, Culture=neutral, PublicKeyToken=null 0.23.0.8
 - C:\Program Files\pollination\plugin\7.0\Pollination\PollinationSDK.dll
Pollination.UI.Rhino, Version=1.6.37.0, Culture=neutral, PublicKeyToken=null 1.6.37.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.UI.Rhino.dll


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.GH2022070716.txt
2022-07-07 16:57:09.478 -07:00 [INF] PollinationSignInAsync: login from https://auth.pollination.cloud/sdk-login
2022-07-07 16:57:15.351 -07:00 [INF] Token expires at: 07/07/2022 17:57:15
2022-07-07 16:57:15.397 -07:00 [INF] CheckGetUser: logged in as jakechevriersg
2022-07-07 16:57:16.401 -07:00 [INF] PollinationSignInAsync: closing the listener
2022-07-07 16:57:20.048 -07:00 [INF] Loading JobsDialog for project demo!
2022-07-07 16:57:40.883 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854].
2022-07-07 16:57:40.885 -07:00 [INF] WatchJobStatusAsync: job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854] is [Created]. Started at: 10:50:06 AM, finished at: 12:00:00 AM
2022-07-07 16:57:40.892 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "87d86c9d-03e2-4409-a4f5-862d73b08854",
  "started_at": "2022-07-07T17:50:06.566251",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-07 16:58:06.196 -07:00 [INF] Loading JobsDialog for project newproject!
2022-07-07 16:58:18.253 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854].
2022-07-07 16:58:18.253 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "87d86c9d-03e2-4409-a4f5-862d73b08854",
  "started_at": "2022-07-07T17:50:06.566251",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-07 16:58:18.255 -07:00 [INF] WatchJobStatusAsync: job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854] is [Created]. Started at: 10:50:06 AM, finished at: 12:00:00 AM
2022-07-07 16:58:21.506 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854].
2022-07-07 16:58:21.506 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "87d86c9d-03e2-4409-a4f5-862d73b08854",
  "started_at": "2022-07-07T17:50:06.566251",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-07 16:58:21.512 -07:00 [INF] WatchJobStatusAsync: job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854] is [Created]. Started at: 10:50:06 AM, finished at: 12:00:00 AM
2022-07-07 16:58:27.255 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854].
2022-07-07 16:58:27.255 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "87d86c9d-03e2-4409-a4f5-862d73b08854",
  "started_at": "2022-07-07T17:50:06.566251",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-07 16:58:27.256 -07:00 [INF] WatchJobStatusAsync: job [smithgroup/newproject/87d86c9d-03e2-4409-a4f5-862d73b08854] is [Created]. Started at: 10:50:06 AM, finished at: 12:00:00 AM
2022-07-07 16:58:35.080 -07:00 [INF] Loading JobsDialog for project newproject!
2022-07-07 16:58:56.008 -07:00 [INF] Loading JobsDialog for project newproject!

mostapha · July 8, 2022, 4:46pm

This is related to the other conversation that we are already having with Jake. We need to set up a call and resolve this sooner than later.

mingbo · July 11, 2022, 1:23pm

Hi @jakechevriersg, this log helps. We cross-checked our logs on the server-side, but we are still unclear why the “CheckJobStatus” component doesn’t work sometime on your side, and it suddenlly it works again after a few hours.

We have also discussed this issue internally but we still couldn’t make any conclusions on what went wrong on your side.
Meantime, I have updated the plugin which is able to capture more logs and hopefully, this time could provide us with more details on this issue. Please install the newest version and try again.

Any chance that you could try this on another machine or other internet environment? Like on your colleagues’ machine?

Sorry again for this trouble and keep us posted.

jakechevriersg · July 11, 2022, 6:08pm

Updated to the latest plugin, still seeing the delay, I’ll try to get on someone elses computer but our team is pretty busy right now, I could also try to get the plugin updated on a remote workshare station…

2022-07-11 11:04:55.501 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261].
2022-07-11 11:04:55.501 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-11 11:04:55.503 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-11 11:04:55.504 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-11 11:04:55.830 -07:00 [ERR] WatchJobStatusAsync: failed to watch job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261].
System.OperationCanceledException: The operation was canceled.
   at System.Threading.CancellationToken.ThrowOperationCanceledException()
   at PollinationSDK.Wrapper.ScheduledJobInfo.<WatchJobStatusAsync>d__26.MoveNext()
2022-07-11 11:04:55.831 -07:00 [ERR] CheckJobStatus-WatchJobAsync: failed to check a job CLOUD:smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261
System.OperationCanceledException: The operation was canceled.
   at PollinationSDK.Wrapper.ScheduledJobInfo.<WatchJobStatusAsync>d__26.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Pollination.GH.Component.CheckJobStatus.<>c__DisplayClass20_1.<<WatchJobAsync>b__1>d.MoveNext()
--- End of stack trace from previous location where exception was thrown ---
   at System.Runtime.ExceptionServices.ExceptionDispatchInfo.Throw()
   at System.Runtime.CompilerServices.TaskAwaiter.ThrowForNonSuccess(Task task)
   at System.Runtime.CompilerServices.TaskAwaiter.HandleNonSuccessAndDebuggerNotification(Task task)
   at Pollination.GH.Component.CheckJobStatus.<WatchJobAsync>d__20.MoveNext()
2022-07-11 11:05:10.111 -07:00 [INF] Loading JobsDialog for project demo!
2022-07-11 11:05:31.972 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261].
2022-07-11 11:05:31.972 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-11 11:05:31.973 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-11 11:05:31.974 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-11 11:05:34.847 -07:00 [INF] Loading JobsDialog for project newproject!

mingbo · July 11, 2022, 7:07pm

Thanks @jakechevriersg

Finally, find the source of the issue. It seems the “WatchJobStatusAsync” was canceled “manually”. I will also follow this lead to investigate this issue.

2022-07-11 11:04:55.830 -07:00 [ERR] WatchJobStatusAsync: failed to watch job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261].
System.OperationCanceledException: The operation was canceled.
   at System.Threading.CancellationToken.ThrowOperationCanceledException()
   at PollinationSDK.Wrapper.ScheduledJobInfo.<WatchJobStatusAsync>d__26.MoveNext(
)

mingbo · July 11, 2022, 9:11pm

Hi @jakechevriersg,

Is that possible that you have any other plugins that automatically recompute the components? It is still really odd why the cancellation is triggered.

Record the debugging process

Would you mind recording a video with the following steps with your current version of plugin?:

Start a new Rhino instance (Do not open any existing Rhino and Grasshopper files)
Just use smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261 in a panel for the job input for “Check Job Status” component.

A new test version of plugin

I build a special version of the “Check Job Status” component here, which doesn’t allow to cancel the watching state.

You can download it from here PO_Debug_20220711.zip (3.2 MB).
Unblock the zip file (Right click the zip file and check properties)
Remove all files inside C:\Program Files\pollination\plugin\7.0\Pollination before unzipping the modified plugin files to this folder.
Start a new video recording to do the above two-step debugging process again.

Please let me know if this is too complicated, and we can set up a call to debug this together if you prefer.

jakechevriersg · July 13, 2022, 11:48pm

jakechevriersg · July 14, 2022, 12:04am

Still does not work…

New run id that is still stuck and not able to show results, even though it shows completed online…
smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568

mingbo · July 14, 2022, 12:07am

Thanks @jakechevriersg could you please drop the logs here as well?

jakechevriersg · July 14, 2022, 12:11am

you were one step ahead of me…

2022-07-13 17:05:18.934 -07:00 [INF] Deactivated the license
2022-07-13 17:05:39.742 -07:00 [INF] Rhino: 7.19 (6/14/2022 12:00:00 AM)
2022-07-13 17:05:39.775 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.RH.rhp
Pollination.UI.Rhino, Version=0.1.70.0, Culture=neutral, PublicKeyToken=null 0.1.70.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.UI.Rhino.dll
Pollination.Core, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Core.dll
HoneybeeSchema, Version=1.49.0.21, Culture=neutral, PublicKeyToken=78492e64b924f7c6 1.49.0.21
 - C:\Program Files\pollination\plugin\7.0\Pollination\HoneybeeSchema.dll
PollinationSDK, Version=0.23.0.1, Culture=neutral, PublicKeyToken=null 0.23.0.1
 - C:\Program Files\pollination\plugin\7.0\Pollination\PollinationSDK.dll
Pollination.Key, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Key.dll
Honeybee.UI.Rhino, Version=0.0.0.64, Culture=neutral, PublicKeyToken=null 0.0.0.64
 - C:\Program Files\pollination\plugin\7.0\Pollination\Honeybee.UI.Rhino.dll
LBT.Newtonsoft.Json, Version=12.0.3.23910, Culture=neutral, PublicKeyToken=0a1d4e767f6b6446 12.0.3.23910
 - C:\Program Files\pollination\plugin\7.0\Pollination\LBT.Newtonsoft.Json.dll


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.RH2022071317.txt
2022-07-13 17:05:55.006 -07:00 [INF] Activation: activating for [jakechevriersg]
2022-07-13 17:05:55.007 -07:00 [INF] Activation: logged activation user data [jakechevriersg] to C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\ActivationLog.txt
2022-07-13 17:05:55.464 -07:00 [INF] Activation: activated! [jakechevriersg]
2022-07-13 17:05:55.464 -07:00 [INF] A license (hosted-floating) is activated and used by jakechevriersg.

mingbo · July 14, 2022, 12:16am

Thanks @jakechevriersg,

This log is too new or old, it doesn’t contain any information on what have done in the above two recordings.
There should be several log files in the folder, please zip all logs if possible.

jakechevriersg · July 14, 2022, 12:18am

logs.zip (391.5 KB)

jakechevriersg · July 14, 2022, 12:20am

the most recent log file available…

2022-07-13 17:00:58.888 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-13 17:00:58.897 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.RH.rhp
Pollination.UI.Rhino, Version=0.1.70.0, Culture=neutral, PublicKeyToken=null 0.1.70.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.UI.Rhino.dll
Pollination.Core, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Core.dll
HoneybeeSchema, Version=1.49.0.21, Culture=neutral, PublicKeyToken=78492e64b924f7c6 1.49.0.21
 - C:\Program Files\pollination\plugin\7.0\Pollination\HoneybeeSchema.dll
PollinationSDK, Version=0.23.0.1, Culture=neutral, PublicKeyToken=null 0.23.0.1
 - C:\Program Files\pollination\plugin\7.0\Pollination\PollinationSDK.dll
Pollination.Key, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Key.dll
Honeybee.UI.Rhino, Version=0.0.0.64, Culture=neutral, PublicKeyToken=null 0.0.0.64
 - C:\Program Files\pollination\plugin\7.0\Pollination\Honeybee.UI.Rhino.dll
LBT.Newtonsoft.Json, Version=12.0.3.23910, Culture=neutral, PublicKeyToken=0a1d4e767f6b6446 12.0.3.23910
 - C:\Program Files\pollination\plugin\7.0\Pollination\LBT.Newtonsoft.Json.dll
Pollination.RH.Loader, Version=1.5.0.0, Culture=neutral, PublicKeyToken=null 1.5.0.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.5.0\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.5.0.0, Culture=neutral, PublicKeyToken=null 1.5.0.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.5.0\Pollination.Loader.Share.dll
Pollination.GH, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.GH.gha


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.GH2022071317.txt
2022-07-13 17:01:50.024 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: Login to Pollination cloud is required!
Please right click the component to login first!
   at Pollination.GH.Component.CheckJobStatus.GetScheduledJobInfo(IGH_Goo job)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:01:53.389 -07:00 [INF] PollinationSignInAsync: login from https://auth.pollination.cloud/sdk-login
2022-07-13 17:02:00.788 -07:00 [INF] Token expires at: 07/13/2022 18:02:00
2022-07-13 17:02:00.843 -07:00 [INF] CheckGetUser: logged in as jakechevriersg
2022-07-13 17:02:01.851 -07:00 [INF] PollinationSignInAsync: closing the listener
2022-07-13 17:02:02.004 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261].
2022-07-13 17:02:02.015 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Completed",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "All children runs have finished",
  "finished_at": "2022-07-11T17:23:51",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 1,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:02:02.015 -07:00 [INF] WatchJobStatusAsync: finished status: {
  "status": "Completed",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "e1044052-fd26-424d-80f4-cb5428516261",
  "started_at": "2022-07-11T17:20:56.600948",
  "annotations": {},
  "message": "All children runs have finished",
  "finished_at": "2022-07-11T17:23:51",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 1,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:02:02.015 -07:00 [INF] WatchJobStatusAsync: finished checking job [smithgroup/newproject/e1044052-fd26-424d-80f4-cb5428516261]: [Completed: [02:54]].
2022-07-13 17:02:13.025 -07:00 [INF] Loading JobsDialog for project demo!
2022-07-13 17:02:30.432 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:02:30.432 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:02:30.434 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:02:30.551 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:02:37.848 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:02:37.848 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:02:37.849 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:02:37.850 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:02:52.224 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:02:52.224 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:02:52.224 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:02:52.225 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:05:06.760 -07:00 [INF] Loading JobsDialog for project newproject!
2022-07-13 17:06:14.418 -07:00 [INF] Rhino: 7.19 (06/14/2022 00:00:00)
2022-07-13 17:06:14.426 -07:00 [INF] Pollination Server: https://app.pollination.cloud ; 
Pollination.RH, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.RH.rhp
Pollination.UI.Rhino, Version=0.1.70.0, Culture=neutral, PublicKeyToken=null 0.1.70.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.UI.Rhino.dll
Pollination.Core, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Core.dll
HoneybeeSchema, Version=1.49.0.21, Culture=neutral, PublicKeyToken=78492e64b924f7c6 1.49.0.21
 - C:\Program Files\pollination\plugin\7.0\Pollination\HoneybeeSchema.dll
PollinationSDK, Version=0.23.0.1, Culture=neutral, PublicKeyToken=null 0.23.0.1
 - C:\Program Files\pollination\plugin\7.0\Pollination\PollinationSDK.dll
Pollination.Key, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.Key.dll
Honeybee.UI.Rhino, Version=0.0.0.64, Culture=neutral, PublicKeyToken=null 0.0.0.64
 - C:\Program Files\pollination\plugin\7.0\Pollination\Honeybee.UI.Rhino.dll
LBT.Newtonsoft.Json, Version=12.0.3.23910, Culture=neutral, PublicKeyToken=0a1d4e767f6b6446 12.0.3.23910
 - C:\Program Files\pollination\plugin\7.0\Pollination\LBT.Newtonsoft.Json.dll
Pollination.RH.Loader, Version=1.5.0.0, Culture=neutral, PublicKeyToken=null 1.5.0.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.5.0\Pollination.RH.Loader.rhp
Pollination.Loader.Share, Version=1.5.0.0, Culture=neutral, PublicKeyToken=null 1.5.0.0
 - C:\ProgramData\McNeel\Rhinoceros\packages\7.0\Pollination\1.5.0\Pollination.Loader.Share.dll
Pollination.GH, Version=0.1.0.0, Culture=neutral, PublicKeyToken=null 0.1.0.0
 - C:\Program Files\pollination\plugin\7.0\Pollination\Pollination.GH.gha


Log: 
C:\Users\jchevrier.SMITHGROUP\AppData\Roaming\pollination\logs\logs.pollination.RH2022071317.txt
2022-07-13 17:06:35.210 -07:00 [INF] PollinationSignInAsync: login from https://auth.pollination.cloud/sdk-login
2022-07-13 17:06:44.716 -07:00 [INF] Token expires at: 07/13/2022 18:06:44
2022-07-13 17:06:44.755 -07:00 [INF] CheckGetUser: logged in as jakechevriersg
2022-07-13 17:06:45.760 -07:00 [INF] PollinationSignInAsync: closing the listener
2022-07-13 17:06:48.338 -07:00 [INF] Loading JobsDialog for project demo!
2022-07-13 17:07:15.792 -07:00 [ERR] Failed to get project!
PollinationSDK.Client.ApiException: Error calling ListProjects: Internal Server Error
   at PollinationSDK.Api.ProjectsApi.ListProjectsWithHttpInfo(String search, List`1 ids, List`1 names, List`1 owner, Nullable`1 _public, List`1 permissions, Nullable`1 sortBy, Nullable`1 sortOrder, Nullable`1 page, Nullable`1 perPage)
   at PollinationSDK.Api.ProjectsApi.ListProjects(String search, List`1 ids, List`1 names, List`1 owner, Nullable`1 _public, List`1 permissions, Nullable`1 sortBy, Nullable`1 sortOrder, Nullable`1 page, Nullable`1 perPage)
   at Pollination.UI.ProjectsDialog.GetProjects(Int32 page)
2022-07-13 17:07:44.886 -07:00 [INF] Loading JobsDialog for project demo!
2022-07-13 17:09:37.569 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:09:37.577 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:09:37.577 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:09:37.578 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:11:28.495 -07:00 [INF] Loading JobsDialog for project newproject!
2022-07-13 17:13:25.077 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:13:25.077 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:13:25.078 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:13:25.081 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:19:01.478 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:19:01.478 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:19:01.478 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:19:01.481 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}
2022-07-13 17:19:10.712 -07:00 [INF] WatchJobStatusAsync: checking job [smithgroup/newproject/bc339b52-3912-4bde-b5d6-ed82ca4c7568].
2022-07-13 17:19:10.712 -07:00 [ERR] GetRunInfo: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
2022-07-13 17:19:10.713 -07:00 [ERR] CheckJobStatus: there is an issue when checking a job status.
System.ArgumentException: [Error] Job status: [Created]. There is no run available in this job
   at Pollination.GH.Component.CheckJobStatus.GetJobRuns(IGH_DataAccess DA, List`1 runIndex)
   at Pollination.GH.Component.CheckJobStatus.SolveInstance(IGH_DataAccess DA)
2022-07-13 17:19:10.714 -07:00 [INF] WatchJobStatusAsync: init status: {
  "status": "Created",
  "type": "JobStatus",
  "api_version": "v1beta1",
  "id": "bc339b52-3912-4bde-b5d6-ed82ca4c7568",
  "started_at": "2022-07-13T23:38:37.569552",
  "annotations": {},
  "message": "Job created",
  "source": "",
  "runs_pending": 0,
  "runs_running": 0,
  "runs_completed": 0,
  "runs_failed": 0,
  "runs_cancelled": 0
}

mingbo · July 27, 2022, 9:04pm

Hi @jakechevriersg,

After some investigations on both our server-side and plugin. Unfortunately, I am still unclear about what causes your issue.

Could you uncheck “Non-blocking” mode here to see if it could load a finished job?

Note: this would freeze the Grasshopper until the job is done, so do not try to do this on a big undergoing job.

Could you also please ask your colleagues to test if the issue happens on their machine? or test it on your personal machine if possible.

jakechevriersg · July 27, 2022, 10:01pm

Good or Bad news (not sure yet! ) I’m getting a new laptop tomorrow (thursday), so I’ll have a different point of reference here pretty soon…

I’ll touch base once I’ve had a chance to test out the latest installer on the new computer

mingbo · July 27, 2022, 10:13pm

Hi @jakechevriersg, please keep us updated!

jakechevriersg · August 9, 2022, 11:01pm

So unfortunately, same results with new laptop, but with some new insights…

I ran a model on my own laptop and found the same results, says “created” even though the run has completed and the pollination select job window shows it complete.

I had a colleague use the same exact .3dm file and .gh definition to pollinate the jobs on their computer (with my login) and it ran successfully in the cloud, but they had the same exact job “created/completed” error with their check job status component.
Where it gets weird, is as soon their jobs completed on the cloud, I tried the check job status on my laptop and was able to access those results immediately after they completed. It seems the issue happens on the specific computer that pollinates those jobs. This is in line with Mostapha being able to access the results right after my runs complete, even though I’m still stuck on “job create”.

Hopefully this insight is helpful in someway! happy to answer more questions.