[07:34:04.243] New invocation is queued and will start shortly
[07:34:05.533] Starting the invocation (attempt 1)
[07:34:05.597] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:34:05.597] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:34:05.597] Buildbucket request:
{
"bucket": "luci.chromium.ci",
"client_operation_id": "9101852937266929840",
"parameters_json": "{\"builder_name\":\"Windows deterministic\",\"properties\":{\"branch\":\"refs/heads/master\",\"repository\":\"https://chromium.googlesource.com/chromium/src.git\",\"revision\":\"bbef3a5addbc814790312fd0fe02312fd3e66921\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:Windows deterministic",
"scheduler_invocation_id:9101852937266929840",
"scheduler_job_id:chromium/Windows deterministic",
"user_agent:luci-scheduler",
"buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921",
"gitiles_ref:refs/heads/master"
]
}
[07:34:07.219] Buildbucket response:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535960045779260",
"id": "8936422874995408592",
"lease_expiration_ts": "1536104046864120",
"lease_key": "966626120",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"bbef3a5addbc814790312fd0fe02312fd3e66921\"}}",
"project": "chromium",
"result_details_json": "null",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1535960047093060",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9957",
"builder:Windows deterministic",
"buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101852937266929840",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9957",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936422874995408592",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936422874995408592/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101852937266929840",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:b9015ab329c28cd84b28ec0e12b4612663b264bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fb72be414a5f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535960047093160",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9957",
"utcnow_ts": "1535960047205910"
}
}
[07:34:07.219] Task URL: https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9957
[07:34:07.219] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:2:0) after 1m0s
[07:35:07.238] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:2:0)
[07:35:07.238] Timer tick, asking Buildbucket for the build status
[07:35:07.416] Build 8936422874995408592: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[07:35:07.416] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:3:0) after 1m0s
[07:35:29.614] Received PubSub notification, asking Buildbucket for the build status
[07:35:29.652] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:07.438] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:3:0)
[07:36:07.438] Timer tick, asking Buildbucket for the build status
[07:36:07.472] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:36:07.472] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:5:0) after 1m0s
[07:37:07.488] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:5:0)
[07:37:07.488] Timer tick, asking Buildbucket for the build status
[07:37:07.586] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:37:07.586] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:6:0) after 1m0s
[07:38:07.634] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:6:0)
[07:38:07.634] Timer tick, asking Buildbucket for the build status
[07:38:07.805] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:38:07.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:7:0) after 1m0s
[07:39:07.997] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:7:0)
[07:39:07.997] Timer tick, asking Buildbucket for the build status
[07:39:08.077] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:39:08.077] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:8:0) after 1m0s
[07:40:08.094] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:8:0)
[07:40:08.094] Timer tick, asking Buildbucket for the build status
[07:40:08.176] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:40:08.176] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:9:0) after 1m0s
[07:41:08.202] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:9:0)
[07:41:08.202] Timer tick, asking Buildbucket for the build status
[07:41:08.285] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:41:08.285] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:10:0) after 1m0s
[07:42:08.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:10:0)
[07:42:08.306] Timer tick, asking Buildbucket for the build status
[07:42:08.461] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:42:08.461] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:11:0) after 1m0s
[07:43:08.489] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:11:0)
[07:43:08.489] Timer tick, asking Buildbucket for the build status
[07:43:08.572] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:43:08.572] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:12:0) after 1m0s
[07:44:08.590] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:12:0)
[07:44:08.590] Timer tick, asking Buildbucket for the build status
[07:44:08.786] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:44:08.786] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:13:0) after 1m0s
[07:45:08.837] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:13:0)
[07:45:08.837] Timer tick, asking Buildbucket for the build status
[07:45:08.904] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:45:08.904] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:14:0) after 1m0s
[07:46:09.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:14:0)
[07:46:09.011] Timer tick, asking Buildbucket for the build status
[07:46:09.048] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:46:09.048] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:15:0) after 1m0s
[07:47:09.068] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:15:0)
[07:47:09.068] Timer tick, asking Buildbucket for the build status
[07:47:09.150] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:47:09.150] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:16:0) after 1m0s
[07:48:09.195] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:16:0)
[07:48:09.195] Timer tick, asking Buildbucket for the build status
[07:48:09.384] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:48:09.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:17:0) after 1m0s
[07:49:09.405] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:17:0)
[07:49:09.405] Timer tick, asking Buildbucket for the build status
[07:49:09.540] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:49:09.540] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:18:0) after 1m0s
[07:50:09.567] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:18:0)
[07:50:09.567] Timer tick, asking Buildbucket for the build status
[07:50:09.631] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:50:09.631] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:19:0) after 1m0s
[07:51:09.728] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:19:0)
[07:51:09.728] Timer tick, asking Buildbucket for the build status
[07:51:09.858] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:51:09.858] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:20:0) after 1m0s
[07:52:09.960] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:20:0)
[07:52:09.960] Timer tick, asking Buildbucket for the build status
[07:52:10.027] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:52:10.027] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:21:0) after 1m0s
[07:53:10.052] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:21:0)
[07:53:10.052] Timer tick, asking Buildbucket for the build status
[07:53:10.121] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:53:10.121] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:22:0) after 1m0s
[07:54:10.250] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:22:0)
[07:54:10.250] Timer tick, asking Buildbucket for the build status
[07:54:10.290] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:54:10.290] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:23:0) after 1m0s
[07:55:10.382] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:23:0)
[07:55:10.382] Timer tick, asking Buildbucket for the build status
[07:55:10.453] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:55:10.453] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:24:0) after 1m0s
[07:56:10.535] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:24:0)
[07:56:10.535] Timer tick, asking Buildbucket for the build status
[07:56:10.653] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:56:10.653] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:25:0) after 1m0s
[07:57:10.670] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:25:0)
[07:57:10.670] Timer tick, asking Buildbucket for the build status
[07:57:10.717] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:57:10.717] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:26:0) after 1m0s
[07:58:10.755] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:26:0)
[07:58:10.755] Timer tick, asking Buildbucket for the build status
[07:58:10.941] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:58:10.941] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:27:0) after 1m0s
[07:59:10.959] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:27:0)
[07:59:10.959] Timer tick, asking Buildbucket for the build status
[07:59:11.106] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[07:59:11.106] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:28:0) after 1m0s
[08:00:11.128] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:28:0)
[08:00:11.128] Timer tick, asking Buildbucket for the build status
[08:00:11.191] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:00:11.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:29:0) after 1m0s
[08:01:11.239] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:29:0)
[08:01:11.239] Timer tick, asking Buildbucket for the build status
[08:01:11.276] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:01:11.276] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:30:0) after 1m0s
[08:02:11.296] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:30:0)
[08:02:11.296] Timer tick, asking Buildbucket for the build status
[08:02:11.352] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:02:11.352] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:31:0) after 1m0s
[08:03:11.370] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:31:0)
[08:03:11.370] Timer tick, asking Buildbucket for the build status
[08:03:11.467] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:03:11.467] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:32:0) after 1m0s
[08:04:11.530] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:32:0)
[08:04:11.530] Timer tick, asking Buildbucket for the build status
[08:04:11.623] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:04:11.623] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:33:0) after 1m0s
[08:05:11.642] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:33:0)
[08:05:11.642] Timer tick, asking Buildbucket for the build status
[08:05:11.784] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:05:11.784] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:34:0) after 1m0s
[08:06:11.804] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:34:0)
[08:06:11.804] Timer tick, asking Buildbucket for the build status
[08:06:11.880] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:06:11.881] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:35:0) after 1m0s
[08:07:11.897] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:35:0)
[08:07:11.897] Timer tick, asking Buildbucket for the build status
[08:07:11.953] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:07:11.953] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:36:0) after 1m0s
[08:08:11.972] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:36:0)
[08:08:11.972] Timer tick, asking Buildbucket for the build status
[08:08:12.246] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:08:12.246] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:37:0) after 1m0s
[08:09:12.261] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:37:0)
[08:09:12.261] Timer tick, asking Buildbucket for the build status
[08:09:12.363] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:09:12.363] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:38:0) after 1m0s
[08:10:12.384] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:38:0)
[08:10:12.384] Timer tick, asking Buildbucket for the build status
[08:10:12.531] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:10:12.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:39:0) after 1m0s
[08:11:12.554] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:39:0)
[08:11:12.554] Timer tick, asking Buildbucket for the build status
[08:11:12.654] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:11:12.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:40:0) after 1m0s
[08:12:12.674] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:40:0)
[08:12:12.674] Timer tick, asking Buildbucket for the build status
[08:12:12.811] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:12:12.811] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:41:0) after 1m0s
[08:13:12.912] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:41:0)
[08:13:12.912] Timer tick, asking Buildbucket for the build status
[08:13:12.971] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:13:12.971] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:42:0) after 1m0s
[08:14:12.991] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:42:0)
[08:14:12.991] Timer tick, asking Buildbucket for the build status
[08:14:13.145] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:14:13.145] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:43:0) after 1m0s
[08:15:13.164] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:43:0)
[08:15:13.164] Timer tick, asking Buildbucket for the build status
[08:15:13.237] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:15:13.237] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:44:0) after 1m0s
[08:16:13.256] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:44:0)
[08:16:13.256] Timer tick, asking Buildbucket for the build status
[08:16:13.507] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:16:13.507] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:45:0) after 1m0s
[08:17:13.570] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:45:0)
[08:17:13.570] Timer tick, asking Buildbucket for the build status
[08:17:13.711] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:17:13.711] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:46:0) after 1m0s
[08:18:13.746] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:46:0)
[08:18:13.746] Timer tick, asking Buildbucket for the build status
[08:18:13.798] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:18:13.798] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:47:0) after 1m0s
[08:19:13.854] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:47:0)
[08:19:13.854] Timer tick, asking Buildbucket for the build status
[08:19:13.942] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:19:13.942] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:48:0) after 1m0s
[08:20:13.962] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:48:0)
[08:20:13.962] Timer tick, asking Buildbucket for the build status
[08:20:14.049] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:20:14.049] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:49:0) after 1m0s
[08:21:14.153] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:49:0)
[08:21:14.153] Timer tick, asking Buildbucket for the build status
[08:21:14.281] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:21:14.281] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:50:0) after 1m0s
[08:22:14.306] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:50:0)
[08:22:14.307] Timer tick, asking Buildbucket for the build status
[08:22:14.481] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:22:14.481] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:51:0) after 1m0s
[08:23:14.514] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:51:0)
[08:23:14.514] Timer tick, asking Buildbucket for the build status
[08:23:14.687] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:23:14.687] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:52:0) after 1m0s
[08:24:14.708] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:52:0)
[08:24:14.708] Timer tick, asking Buildbucket for the build status
[08:24:14.764] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:24:14.764] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:53:0) after 1m0s
[08:25:14.801] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:53:0)
[08:25:14.801] Timer tick, asking Buildbucket for the build status
[08:25:14.990] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:25:14.990] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:54:0) after 1m0s
[08:26:15.011] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:54:0)
[08:26:15.011] Timer tick, asking Buildbucket for the build status
[08:26:15.244] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:26:15.244] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:55:0) after 1m0s
[08:27:15.262] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:55:0)
[08:27:15.262] Timer tick, asking Buildbucket for the build status
[08:27:15.320] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:27:15.320] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:56:0) after 1m0s
[08:28:15.342] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:56:0)
[08:28:15.342] Timer tick, asking Buildbucket for the build status
[08:28:15.436] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:28:15.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:57:0) after 1m0s
[08:29:15.458] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:57:0)
[08:29:15.458] Timer tick, asking Buildbucket for the build status
[08:29:15.655] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:29:15.655] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:58:0) after 1m0s
[08:30:15.688] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:58:0)
[08:30:15.688] Timer tick, asking Buildbucket for the build status
[08:30:15.821] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:30:15.821] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:59:0) after 1m0s
[08:31:15.844] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:59:0)
[08:31:15.844] Timer tick, asking Buildbucket for the build status
[08:31:15.913] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:31:15.913] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:60:0) after 1m0s
[08:32:15.932] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:60:0)
[08:32:15.932] Timer tick, asking Buildbucket for the build status
[08:32:15.968] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:32:15.968] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:61:0) after 1m0s
[08:33:16.119] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:61:0)
[08:33:16.119] Timer tick, asking Buildbucket for the build status
[08:33:16.312] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:33:16.312] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:62:0) after 1m0s
[08:34:16.344] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:62:0)
[08:34:16.344] Timer tick, asking Buildbucket for the build status
[08:34:16.436] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:34:16.436] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:63:0) after 1m0s
[08:35:16.455] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:63:0)
[08:35:16.455] Timer tick, asking Buildbucket for the build status
[08:35:16.531] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:35:16.531] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:64:0) after 1m0s
[08:36:16.553] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:64:0)
[08:36:16.553] Timer tick, asking Buildbucket for the build status
[08:36:16.642] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:36:16.642] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:65:0) after 1m0s
[08:37:16.660] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:65:0)
[08:37:16.660] Timer tick, asking Buildbucket for the build status
[08:37:16.855] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:37:16.855] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:66:0) after 1m0s
[08:38:16.878] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:66:0)
[08:38:16.878] Timer tick, asking Buildbucket for the build status
[08:38:16.959] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:38:16.959] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:67:0) after 1m0s
[08:39:16.998] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:67:0)
[08:39:16.998] Timer tick, asking Buildbucket for the build status
[08:39:17.063] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:39:17.063] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:68:0) after 1m0s
[08:40:17.084] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:68:0)
[08:40:17.084] Timer tick, asking Buildbucket for the build status
[08:40:17.191] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:40:17.191] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:69:0) after 1m0s
[08:41:17.225] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:69:0)
[08:41:17.225] Timer tick, asking Buildbucket for the build status
[08:41:17.384] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:41:17.384] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:70:0) after 1m0s
[08:42:17.404] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:70:0)
[08:42:17.404] Timer tick, asking Buildbucket for the build status
[08:42:17.474] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:42:17.474] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:71:0) after 1m0s
[08:43:17.494] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:71:0)
[08:43:17.494] Timer tick, asking Buildbucket for the build status
[08:43:17.654] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:43:17.654] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:72:0) after 1m0s
[08:44:17.675] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:72:0)
[08:44:17.675] Timer tick, asking Buildbucket for the build status
[08:44:17.804] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:44:17.804] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:73:0) after 1m0s
[08:45:17.826] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:73:0)
[08:45:17.826] Timer tick, asking Buildbucket for the build status
[08:45:17.863] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:45:17.863] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:74:0) after 1m0s
[08:46:17.883] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:74:0)
[08:46:17.883] Timer tick, asking Buildbucket for the build status
[08:46:17.945] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:46:17.945] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:75:0) after 1m0s
[08:47:17.965] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:75:0)
[08:47:17.965] Timer tick, asking Buildbucket for the build status
[08:47:18.037] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:47:18.037] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:76:0) after 1m0s
[08:48:18.504] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:76:0)
[08:48:18.504] Timer tick, asking Buildbucket for the build status
[08:48:18.589] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:48:18.589] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:77:0) after 1m0s
[08:49:18.646] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:77:0)
[08:49:18.646] Timer tick, asking Buildbucket for the build status
[08:49:18.755] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:49:18.755] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:78:0) after 1m0s
[08:50:18.779] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:78:0)
[08:50:18.779] Timer tick, asking Buildbucket for the build status
[08:50:18.870] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:50:18.870] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:79:0) after 1m0s
[08:51:18.922] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:79:0)
[08:51:18.922] Timer tick, asking Buildbucket for the build status
[08:51:18.955] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:51:18.955] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:80:0) after 1m0s
[08:52:18.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:80:0)
[08:52:18.977] Timer tick, asking Buildbucket for the build status
[08:52:19.099] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:52:19.099] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:81:0) after 1m0s
[08:53:19.121] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:81:0)
[08:53:19.121] Timer tick, asking Buildbucket for the build status
[08:53:19.163] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:53:19.163] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:82:0) after 1m0s
[08:54:19.235] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:82:0)
[08:54:19.235] Timer tick, asking Buildbucket for the build status
[08:54:19.410] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:54:19.410] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:83:0) after 1m0s
[08:55:19.490] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:83:0)
[08:55:19.490] Timer tick, asking Buildbucket for the build status
[08:55:19.620] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:55:19.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:84:0) after 1m0s
[08:56:19.649] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:84:0)
[08:56:19.649] Timer tick, asking Buildbucket for the build status
[08:56:19.689] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:56:19.689] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:85:0) after 1m0s
[08:57:19.715] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:85:0)
[08:57:19.715] Timer tick, asking Buildbucket for the build status
[08:57:19.805] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:57:19.805] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:86:0) after 1m0s
[08:58:19.856] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:86:0)
[08:58:19.856] Timer tick, asking Buildbucket for the build status
[08:58:20.034] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:58:20.034] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:87:0) after 1m0s
[08:59:20.060] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:87:0)
[08:59:20.060] Timer tick, asking Buildbucket for the build status
[08:59:20.313] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[08:59:20.313] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:88:0) after 1m0s
[09:00:20.337] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:88:0)
[09:00:20.337] Timer tick, asking Buildbucket for the build status
[09:00:20.460] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:00:20.460] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:89:0) after 1m0s
[09:01:20.482] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:89:0)
[09:01:20.482] Timer tick, asking Buildbucket for the build status
[09:01:20.548] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:01:20.548] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:90:0) after 1m0s
[09:02:20.572] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:90:0)
[09:02:20.572] Timer tick, asking Buildbucket for the build status
[09:02:20.620] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:02:20.620] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:91:0) after 1m0s
[09:03:20.647] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:91:0)
[09:03:20.647] Timer tick, asking Buildbucket for the build status
[09:03:20.683] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:03:20.683] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:92:0) after 1m0s
[09:04:20.704] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:92:0)
[09:04:20.704] Timer tick, asking Buildbucket for the build status
[09:04:20.838] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:04:20.838] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:93:0) after 1m0s
[09:05:20.888] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:93:0)
[09:05:20.888] Timer tick, asking Buildbucket for the build status
[09:05:21.247] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:05:21.247] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:94:0) after 1m0s
[09:06:21.267] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:94:0)
[09:06:21.267] Timer tick, asking Buildbucket for the build status
[09:06:21.326] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:06:21.326] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:95:0) after 1m0s
[09:07:21.348] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:95:0)
[09:07:21.348] Timer tick, asking Buildbucket for the build status
[09:07:21.403] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:07:21.403] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:96:0) after 1m0s
[09:08:21.450] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:96:0)
[09:08:21.450] Timer tick, asking Buildbucket for the build status
[09:08:21.536] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:08:21.536] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:97:0) after 1m0s
[09:09:21.560] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:97:0)
[09:09:21.560] Timer tick, asking Buildbucket for the build status
[09:09:21.750] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:09:21.750] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:98:0) after 1m0s
[09:10:21.774] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:98:0)
[09:10:21.774] Timer tick, asking Buildbucket for the build status
[09:10:21.903] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:10:21.903] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:99:0) after 1m0s
[09:11:21.943] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:99:0)
[09:11:21.943] Timer tick, asking Buildbucket for the build status
[09:11:23.946] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:11:23.946] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:100:0) after 1m0s
[09:12:23.977] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:100:0)
[09:12:23.977] Timer tick, asking Buildbucket for the build status
[09:12:24.075] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:12:24.075] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:101:0) after 1m0s
[09:13:24.099] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:101:0)
[09:13:24.099] Timer tick, asking Buildbucket for the build status
[09:13:24.167] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:13:24.167] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:102:0) after 1m0s
[09:14:24.191] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:102:0)
[09:14:24.191] Timer tick, asking Buildbucket for the build status
[09:14:24.277] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:14:24.277] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:103:0) after 1m0s
[09:15:24.300] Handling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:103:0)
[09:15:24.300] Timer tick, asking Buildbucket for the build status
[09:15:24.420] Build 8936422874995408592: status "STARTED", result "", failure_reason "", cancelation_reason ""
[09:15:24.420] Scheduling timer "check-buildbucket-build-status" (chromium/Windows deterministic:9101852937266929840:104:0) after 1m0s
[09:16:00.989] Received PubSub notification, asking Buildbucket for the build status
[09:16:01.052] Build 8936422874995408592: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[09:16:01.052] Buildbucket build:
{
"build": {
"bucket": "luci.chromium.ci",
"canary_preference": "AUTO",
"completed_ts": "1535966159691170",
"created_by": "user:luci-scheduler@appspot.gserviceaccount.com",
"created_ts": "1535960045779260",
"failure_reason": "BUILD_FAILURE",
"id": "8936422874995408592",
"parameters_json": "{\"builder_name\": \"Windows deterministic\", \"properties\": {\"branch\": \"refs/heads/master\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"revision\": \"bbef3a5addbc814790312fd0fe02312fd3e66921\"}}",
"project": "chromium",
"result": "FAILURE",
"result_details_json": "{\"ui\": {\"info\": \"Failure compare_build_artifacts\"}, \"build_run_result\": {\"infraFailure\": null, \"recipeResult\": {\"failure\": {\"failure\": {\"step\": \"compare_build_artifacts\"}, \"humanReason\": \"Step('compare_build_artifacts') failed with return_code 1\"}}, \"recipeExitCode\": {\"value\": 1}, \"recipe\": {\"name\": \"swarming/deterministic_build\", \"repository\": \"\", \"revision\": \"\"}, \"annotationUrl\": \"logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936422874995408592/+/annotations\"}, \"swarming\": {\"bot_dimensions\": {\"machine_type\": [\"n1-standard-8\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"], \"locale\": [\"en_US.cp1252\"], \"os\": [\"Windows\", \"Windows-10\", \"Windows-10-15063\"], \"builder\": [\"Windows deterministic\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Haswell_GCE\"], \"python\": [\"2.7.6\"], \"server_version\": [\"3740-90b257a\"], \"image\": [\"chrome-win10-1703-2018-04-20-61acaf8983f\"], \"gpu\": [\"none\"], \"cores\": [\"8\"], \"integrity\": [\"high\"], \"id\": [\"swarm2173-c4\"], \"pool\": [\"luci.chromium.ci\"], \"caches\": [\"git\", \"goma_v2\", \"vpython\"]}}, \"properties\": {\"got_nacl_revision\": \"cb6ec4b1f91af259cbdfd1bd22bb98ed5551955e\", \"got_swarming_client_revision\": \"486c9b53c4d54dd4b95bb6ce0e31160e600dfc11\", \"got_revision\": \"bbef3a5addbc814790312fd0fe02312fd3e66921\", \"recipe\": \"swarming/deterministic_build\", \"got_webrtc_revision_cp\": \"refs/heads/master@{#24472}\", \"$recipe_engine/path\": {\"cache_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\cache\", \"temp_dir\": \"C:\\\\b\\\\swarming\\\\w\\\\ir\\\\tmp\\\\rt\"}, \"got_revision_cp\": \"refs/heads/master@{#588342}\", \"branch\": \"refs/heads/master\", \"revision\": \"bbef3a5addbc814790312fd0fe02312fd3e66921\", \"repository\": \"https://chromium.googlesource.com/chromium/src.git\", \"buildername\": \"Windows deterministic\", \"got_webrtc_revision\": \"f18b35284288ac851b77db88df3e2e8d2273db97\", \"mastername\": \"chromium.fyi\", \"got_angle_revision\": \"9808d38c032a49476dcb4ce3891d6b8bd2128803\", \"buildbucket\": {\"hostname\": \"cr-buildbucket.appspot.com\", \"build\": {\"created_ts\": 1535960045779260, \"tags\": [\"builder:Windows deterministic\", \"buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921\", \"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921\", \"gitiles_ref:refs/heads/master\", \"scheduler_invocation_id:9101852937266929840\", \"scheduler_job_id:chromium/Windows deterministic\", \"user_agent:luci-scheduler\"], \"bucket\": \"luci.chromium.ci\", \"created_by\": \"user:luci-scheduler@appspot.gserviceaccount.com\", \"project\": \"chromium\", \"id\": \"8936422874995408592\"}}, \"got_v8_revision\": \"9c8d1a1ebea28c7e3a5a4f79ed6e4997fa41ab4a\", \"got_v8_revision_cp\": \"refs/heads/7.1.8@{#1}\", \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"buildnumber\": 9957, \"swarm_hashes\": {\"shell_dialogs_unittests\": \"f26b90fb2266a0e48ef46a587a3266564a712871\", \"notification_helper_unittests\": \"4bca865d30a3c93607efdc0a7c6f9b2f2b40a393\", \"gpu_perftests\": \"06cc38e2619ec8b631b706ae87c7841836e9ffdc\", \"vr_common_unittests\": \"05aa658a2da95e2becc1760443eb0015bcc95d7d\", \"telemetry_unittests\": \"8a2037ebd7e4353d450527d1a928d0b508628dcb\", \"load_library_perf_tests\": \"d8e212eecfa3b7073694059bff96aeb923e4b81d\", \"devtools_closure_compile\": \"b34cca3078dd0fb087691b975f49b95513d53f95\", \"chromedriver_py_tests\": \"af961dfa8663f49d13d8d1a447b5c92e733fb7c3\", \"mojo_core_unittests\": \"9074eb1cf98f1cbe0c2249fed4d6e20701b921cb\", \"install_static_unittests\": \"4df4f7e0f510d09a314c5990ab5df9f9a2bb5ba2\", \"leveldb_service_unittests\": \"7af868414687eac65665affbe2fc9f1fd330dc46\", \"mini_installer_tests\": \"22bd5ed21e5df2a3aa1c894e81f906e891c62eb1\", \"jingle_unittests\": \"5f305abe6311c4553374e6ab069caea48262f81f\", \"sbox_integration_tests\": \"0d1a0653678b35bebcf57b207185ab2a9ab6c572\", \"angle_unittests\": \"612dec63084966385c860e1cbed9fb809190d4ac\", \"cast_unittests\": \"c55e6027fac591346216d6459bcf8050e641445e\", \"cc_unittests\": \"bd53491b04da7918f2b7a5945585f0eb108c463b\", \"blink_common_unittests\": \"1433a332b074d923b505d6de9af641d203cd374d\", \"crashpad_tests\": \"04f8b2773c503d9c8b2db9845b4a648c063f1c4b\", \"telemetry_perf_tests_without_chrome\": \"8fdf159903dd32aa094953ee68262d28e9493fb1\", \"metrics_python_tests\": \"a54ccbdcb57a0d4e104d4d9469fa880fb724183b\", \"message_center_unittests\": \"837ba5e7124a589487a6c992876ce51892314d76\", \"ppapi_unittests\": \"3778b6a504ff3066ff61f2b39961b96860d161a8\", \"extensions_unittests\": \"8820c95ffb8d48d1e06674712c4bd0ae64e5d877\", \"gl_unittests_ozone\": \"0341a967ac17b2ba2ff7e7f0dc2b60540e1dd59c\", \"app_shell_unittests\": \"aba51db9de10effc75c480c4c83a73db2fd7b3a1\", \"chrome_app_unittests\": \"b43207cd7693bf38b9c0ee12dd294f65dda0bee6\", \"gl_unittests\": \"0341a967ac17b2ba2ff7e7f0dc2b60540e1dd59c\", \"gcm_unit_tests\": \"2e579b4e7a1ece11f3c54d694b7a06641dd4744c\", \"views_unittests\": \"001a5d29782704f82fd53457b603bed7c891d3b1\", \"content_shell_crash_test\": \"73bcb6bf0ff52fb3932f167a5ce6344b34d79afd\", \"ui_base_unittests\": \"8022a48608967069a91e516564cb52257ac04326\", \"gles2_conform_test\": \"6cbc6e079fea85f0575492df8ba14489022a371a\", \"cronet_unittests\": \"a77cb65003077dec2ad6e000b4affe5389698f93\", \"performance_test_suite\": \"cfedbc19f788dff795ef3c97f3c3ae549a3bf4e8\", \"wm_unittests\": \"69a01905ca0474de4fddfff74d56586900d091a6\", \"boringssl_crypto_tests\": \"e663226a1d085852b76870625357cf2989b3f162\", \"gin_unittests\": \"59e6f0ce00c170c5769b0b1b018fa9d2a1387d44\", \"performance_browser_tests\": \"cf1ecb914aac33e55674b293ab5869db863f1e55\", \"extensions_browsertests\": \"2b1e8d78fda91dfc7c65911c6cf73f878881195b\", \"webkit_python_tests\": \"acc53a78d44fcbdeb97af6a8be4ffaab8086f5c1\", \"headless_unittests\": \"bb68f548e31e7fee7524810d58b5a8f4316dc192\", \"webkit_layout_tests\": \"fe177753a2476a6c0b5cb90b6e90c3152ac76850\", \"vr_pixeltests\": \"4686d8fd159d07dbf5898ec19ba3a37eec9a29fc\", \"setup_unittests\": \"3955e11fbdeac8d4f784325d9a3ec06f7aefca1f\", \"webkit_unit_tests\": \"476e544682875a3d9151a82c07d312ae06a26c78\", \"content_unittests\": \"b77044e9ab909a04b5e1af68504e8325acb2c652\", \"performance_webview_test_suite\": \"6895ff0fa9001fb03fb75acb51b1c37dbfa69785\", \"wtf_unittests\": \"8601851b1c7b046b9f0fea9af2265d885a9cc38e\", \"webkit_layout_tests_exparchive\": \"38d1299c5fe20502a2bfc83b229e4f85aaf41275\", \"chrome_cleaner_unittests\": \"dfd70b611455f4c7a639c4c210d2a732d4af869a\", \"traffic_annotation_auditor_unittests\": \"a0e95081ff75d6db0013a369e12f5860a404f0f3\", \"libjingle_xmpp_unittests\": \"ba3e9285e382c0e12ef54ab18e93c6f6582e1253\", \"remoting_unittests\": \"b686a9a1be8080ff23d9d4df25102da20fd71862\", \"vr_common_perftests\": \"4236787f138add25d0f049e0117e55d3eba553a3\", \"filesystem_service_unittests\": \"e82c5ffee7cf9fea4ce8b2c77be257842b394839\", \"chrome_elf_import_unittests\": \"247bc24db7c275b2de321dbb18346a79fb60c4a3\", \"installer_util_unittests\": \"b82852f320145734825b62d9f606137a6b0dfa70\", \"gfx_unittests\": \"4dd464ffcc44f24a8f21080a40de0fddd02ff738\", \"headless_browsertests\": \"ef3995e20c5f077672a847a27b184c04d826c864\", \"capture_unittests\": \"d61c26de4b5c0ef52a6840203d15309c8715630a\", \"chrome_elf_unittests\": \"f74d4a682d75a7f9147d73385bb2e7fceec6586d\", \"telemetry_gpu_unittests\": \"779ed602c7d9ab9f46e9b333bf61c506c6413751\", \"command_buffer_perftests\": \"904823f9a2f293aebcdb46c1f92413e49e256f78\", \"media_unittests\": \"0d74d23f09b8920adba9a84851e4415c25018728\", \"chromedriver_unittests\": \"2156fff81d982b9860620bb3f07bf8ae5eefdfb6\", \"views_mus_interactive_ui_tests\": \"b0f8a08e5fb06ec80df518ed217a2d335e94678c\", \"storage_unittests\": \"5b926ee639ae4cbe38658b5746dfb18fb1c434ad\", \"media_service_unittests\": \"6c6d821b07587eb166d9270ec9e8d185f8b0db5f\", \"compositor_unittests\": \"649654cf54c689ede8a6453dc4d35c6ac05b5570\", \"latency_unittests\": \"ea702ef49436e5f2530db72d0f7a3a472b57606d\", \"video_decode_accelerator_unittest\": \"7bd7e08aa2c7b0b20b6733e0a53a482a2fe44aac\", \"swiftshader_unittests\": \"45110c2ef5f22ea4a56bd6cdcb5abc6099026537\", \"telemetry_perf_unittests\": \"5977e32758cbcb445ba3d5a1c8c5ef0df56699ab\", \"media_blink_unittests\": \"ec5c18a747f605e778f16f72740c21e68fc3029b\", \"views_mus_unittests\": \"88e11c513fbb29246742065202809dcf2f7b9c66\", \"components_unittests\": \"22b1b40f1efc7e0aa975597ccd162ae118f0ee78\", \"snapshot_unittests\": \"92a9794f318ca5f6725d8b0a29414ee0530b1b96\", \"accessibility_unittests\": \"97840e329e496c73214f9d4a42ed875149fcef87\", \"net_perftests\": \"9531270d550635392d9caba60113179e335af117\", \"aura_unittests\": \"b63da577111aa73c78998bc945b877ec8d6286ab\", \"services_unittests\": \"cc04f4dbe5cf01d203e0aa7935d4ccda6aa73679\", \"components_perftests\": \"c63c74225c2a6bbb66d646f3970cb8a2482b2585\", \"events_unittests\": \"01434a357ed87048210a9b2cb48466ecfa909933\", \"tracing_perftests\": \"79f51222655c115538ba2b5f13cba61ffc288086\", \"chromedriver_replay_unittests\": \"e2ec5b5deadfbf1a0e00246e86e8f3e11e9cace0\", \"ui_touch_selection_unittests\": \"1a09721368aba6e5c9413b445b292e686b74e231\", \"cacheinvalidation_unittests\": \"1f7aa5a45c86b19eba9d028c5357beb874dc4bd2\", \"viz_unittests\": \"f281bbf378957435810dc37a8d1f3f861a2f9689\", \"browser_tests\": \"d589b1ec387776187ab9721619b7d66b604da324\", \"net_unittests\": \"a9aa292de0d35b17aa5825091cf0c0891c1f28e0\", \"midi_unittests\": \"e2837302914e45da40be15cfa6e70daf8a855c50\", \"boringssl_ssl_tests\": \"28bf2836329b1fef0e682d5670280aec182157f0\", \"sbox_validation_tests\": \"b3601dda11ee180b46c63ced23411e9512dd614e\", \"elevation_service_unittests\": \"50fdb40995632aa55b0d144f13d5c7dc8cd90307\", \"gpu_unittests\": \"b6a8c46a092f3fe190510f410a83c57ff4a8392c\", \"native_theme_unittests\": \"2e0418318cb8b9dc6dafa8a464bcea5f19b968ef\", \"interactive_ui_tests\": \"08a2875f66424f93a3a7d4b9e4f4fecd5c8cf958\", \"mojo_unittests\": \"5a6342e7fcd23b78a8ccd9d37ec303d28d61aada\", \"url_unittests\": \"39bba0c2790ec91b5f21d275730f467980cc04a6\", \"media_perftests\": \"3ef48f8769fa85c1ec5510f5bbac2ba3ffba9a66\", \"cronet_tests\": \"e8f8417f350334e0db53b7112e955c990395fd75\", \"google_apis_unittests\": \"8fdd19f1111449762df17f9deb0f3a349533ea77\", \"telemetry_gpu_integration_test\": \"ef2630693e0489e1103d682dbec7a97e73c8cc56\", \"blink_heap_unittests\": \"4fd3e6f5162c253ddac9e0dd8e63017656eb80a8\", \"base_unittests\": \"0b1b85678015440e0a72ed6ab082a61a1d124da9\", \"sync_integration_tests\": \"c1a5cb1a5dc6d7af798516327eb31d810af1fc98\", \"sql_unittests\": \"0cfd034875754da5b8967eac0cfc09cfe8ddb120\", \"courgette_unittests\": \"8ef4c64afe7bb326492803df390ec9522b579ba5\", \"blink_fuzzer_unittests\": \"b2037b674e06c95b22ff9a17577adddbba327322\", \"gl_tests\": \"421eb919ba8bc4a2e088767ef7bbce3efb7b08e6\", \"zucchini_unittests\": \"1986fb3812c179bbaf8ae5011785ce6f8c457e6e\", \"ipc_tests\": \"8b043919dbb9d9ad3eeaa5216bf55d535a62780c\", \"angle_end2end_tests\": \"d4f5a6c8dac90f756d647261ba18c6e8bed4f9fc\", \"blink_platform_unittests\": \"26b4e47acda1f82e95761a38252e16513c9e8248\", \"components_browsertests\": \"5e320fbf7aac760d7b3c08cd4a9faea61e82f3ca\", \"views_perftests\": \"7809326e93bd53d79f8744dd9bc10a42bd4d727c\", \"angle_perftests\": \"3899a9bffcf3b087dd2b236ecb2e2091416d8ec2\", \"display_unittests\": \"8c25fdde36ccde74970405e0f334dddcfa7b7163\", \"sbox_unittests\": \"7b5bbb2d16825cf19436e8a6d7d600a207f05723\", \"service_manager_unittests\": \"9bbb8f19ce0a4d89cdb6fd9e24d84de803c2210e\", \"pdf_unittests\": \"841d7a0c6f4c55e8ee75f34ad4a94ba52a9fbc86\", \"skia_unittests\": \"bf8da3daa4851489674c0b1fd3edf8d21167aa80\", \"angle_white_box_tests\": \"3bb1b82343406a8311f2a7d4ebf46e46765b6c0a\", \"crypto_unittests\": \"8cd641bee7ea28308259e2d3d8f238afcbfeb028\", \"nacl_loader_unittests\": \"e746750b2f68b501b6ab5bd8a4564791ce45ebf2\", \"unit_tests\": \"381759c5d9abe221fda65b8b39c380284b9e8d43\", \"content_browsertests\": \"8cf2154d9cd854433eb1b05308947c503e7d4472\", \"printing_unittests\": \"93b77b35412e3f331fec0b96b7a83e48492dcdbb\", \"device_unittests\": \"7c86376c68f443cf461e797b0e4f2254f659e58a\"}, \"path_config\": \"generic\", \"bot_id\": \"swarm2173-c4\", \"got_buildtools_revision\": \"2dff9c9c74e9d732e6fe57c84ef7fd044cc45d96\"}}",
"service_account": "chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"started_ts": "1535960128689150",
"status": "COMPLETED",
"status_changed_ts": "1535966160268550",
"tags": [
"build_address:luci.chromium.ci/Windows deterministic/9957",
"builder:Windows deterministic",
"buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921",
"buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9101852937266929840",
"scheduler_job_id:chromium/Windows deterministic",
"swarming_dimension:builder:Windows deterministic",
"swarming_dimension:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_dimension:cores:8",
"swarming_dimension:cpu:x86-64",
"swarming_dimension:os:Windows-10",
"swarming_dimension:pool:luci.chromium.ci",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:build_address:luci.chromium.ci/Windows deterministic/9957",
"swarming_tag:buildbucket_bucket:luci.chromium.ci",
"swarming_tag:buildbucket_build_id:8936422874995408592",
"swarming_tag:buildbucket_hostname:cr-buildbucket.appspot.com",
"swarming_tag:buildbucket_template_canary:0",
"swarming_tag:buildbucket_template_revision:ca2c7a741e04d0385e2bf62941ac932dc0521af3",
"swarming_tag:builder:Windows deterministic",
"swarming_tag:buildset:commit/git/bbef3a5addbc814790312fd0fe02312fd3e66921",
"swarming_tag:buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/bbef3a5addbc814790312fd0fe02312fd3e66921",
"swarming_tag:caches:builder_d795d6e07fe37a299fcfc0e872e48757ad306282f024125f803c0465bfd1eeae_v2",
"swarming_tag:cores:8",
"swarming_tag:cpu:x86-64",
"swarming_tag:gitiles_ref:refs/heads/master",
"swarming_tag:log_location:logdog://logs.chromium.org/chromium/buildbucket/cr-buildbucket.appspot.com/8936422874995408592/+/annotations",
"swarming_tag:luci_project:chromium",
"swarming_tag:os:Windows-10",
"swarming_tag:pool:luci.chromium.ci",
"swarming_tag:priority:30",
"swarming_tag:recipe_name:swarming/deterministic_build",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_tag:scheduler_invocation_id:9101852937266929840",
"swarming_tag:scheduler_job_id:chromium/Windows deterministic",
"swarming_tag:service_account:chromium-ci-builder@chops-service-accounts.iam.gserviceaccount.com",
"swarming_tag:swarming.pool.template:skip",
"swarming_tag:swarming.pool.version:b9015ab329c28cd84b28ec0e12b4612663b264bc",
"swarming_tag:user:None",
"swarming_tag:user_agent:luci-scheduler",
"swarming_tag:vpython:native-python-wrapper",
"swarming_task_id:3fb72be414a5f010",
"user_agent:luci-scheduler"
],
"updated_ts": "1535966160271330",
"url": "https://ci.chromium.org/p/chromium/builders/luci.chromium.ci/Windows deterministic/9957",
"utcnow_ts": "1535966161039040"
}
}
[09:16:01.052] Invocation finished in 1h41m56.82376244s with status FAILED