[15:29:59.822] New invocation is queued and will start shortly
[15:30:09.320] Starting the invocation (attempt 1)
[15:30:09.385] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[15:30:09.385] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[15:30:09.385] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9059967196103283184",
"parameters_json": "{\"builder_name\":\"vm-ffi-android-debug-arm\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://dart.googlesource.com/sdk\",\"revision\":\"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@16e7647c8618a4ab7f66753a3e1774e11f40ef68\",\"title\":\"16e7647c8618a4ab7f66753a3e1774e11f40ef68\",\"url\":\"https://dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:vm-ffi-android-debug-arm",
"scheduler_invocation_id:9059967196103283184",
"scheduler_job_id:dart/vm-ffi-android-debug-arm",
"user_agent:luci-scheduler",
"buildset:commit/git/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"gitiles_ref:refs/heads/master"
]
}
[15:30:11.150] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1575905409952751",
"id": "8894537124812333632",
"parameters_json": "{\"builder_name\": \"vm-ffi-android-debug-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"title\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"url\": \"https://dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575905410736868",
"tags": [
"build_address:luci.dart.ci.sandbox/vm-ffi-android-debug-arm/2662",
"builder:vm-ffi-android-debug-arm",
"buildset:commit/git/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059967196103283184",
"scheduler_job_id:dart/vm-ffi-android-debug-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8894537124812333632/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1575905410737894",
"url": "https://ci.chromium.org/b/8894537124812333632",
"utcnow_ts": "1575905411121759"
}
}
[15:30:11.150] Task URL: https://ci.chromium.org/b/8894537124812333632
[15:30:11.150] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:2:0) after 1m0s
[15:31:11.284] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:2:0)
[15:31:11.284] Timer tick, asking Buildbucket for the build status
[15:31:11.550] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:31:11.550] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:3:0) after 1m0s
[15:32:11.800] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:3:0)
[15:32:11.800] Timer tick, asking Buildbucket for the build status
[15:32:14.987] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:32:14.987] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:4:0) after 1m0s
[15:33:15.053] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:4:0)
[15:33:15.053] Timer tick, asking Buildbucket for the build status
[15:33:15.533] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:33:15.533] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:5:0) after 1m0s
[15:34:15.601] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:5:0)
[15:34:15.601] Timer tick, asking Buildbucket for the build status
[15:34:16.447] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:34:16.447] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:6:0) after 1m0s
[15:35:16.608] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:6:0)
[15:35:16.608] Timer tick, asking Buildbucket for the build status
[15:35:17.354] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:35:17.354] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:7:0) after 1m0s
[15:36:17.771] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:7:0)
[15:36:17.771] Timer tick, asking Buildbucket for the build status
[15:36:18.084] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:36:18.084] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:8:0) after 1m0s
[15:37:18.616] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:8:0)
[15:37:18.616] Timer tick, asking Buildbucket for the build status
[15:37:18.881] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:37:18.881] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:9:0) after 1m0s
[15:38:19.058] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:9:0)
[15:38:19.058] Timer tick, asking Buildbucket for the build status
[15:38:19.334] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:38:19.334] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:10:0) after 1m0s
[15:39:19.608] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:10:0)
[15:39:19.608] Timer tick, asking Buildbucket for the build status
[15:39:20.113] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:39:20.113] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:11:0) after 1m0s
[15:40:20.244] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:11:0)
[15:40:20.244] Timer tick, asking Buildbucket for the build status
[15:40:20.424] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:40:20.424] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:12:0) after 1m0s
[15:41:20.651] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:12:0)
[15:41:20.651] Timer tick, asking Buildbucket for the build status
[15:41:21.329] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:41:21.329] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:13:0) after 1m0s
[15:42:21.542] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:13:0)
[15:42:21.543] Timer tick, asking Buildbucket for the build status
[15:42:23.227] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:42:23.227] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:14:0) after 1m0s
[15:43:23.372] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:14:0)
[15:43:23.373] Timer tick, asking Buildbucket for the build status
[15:43:23.512] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:43:23.512] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:15:0) after 1m0s
[15:44:23.676] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:15:0)
[15:44:23.676] Timer tick, asking Buildbucket for the build status
[15:44:23.891] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:44:23.891] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:16:0) after 1m0s
[15:45:24.019] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:16:0)
[15:45:24.019] Timer tick, asking Buildbucket for the build status
[15:45:24.293] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:45:24.293] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:17:0) after 1m0s
[15:46:24.306] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:17:0)
[15:46:24.306] Timer tick, asking Buildbucket for the build status
[15:46:24.776] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:46:24.776] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:18:0) after 1m0s
[15:47:24.935] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:18:0)
[15:47:24.935] Timer tick, asking Buildbucket for the build status
[15:47:25.180] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:47:25.181] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:19:0) after 1m0s
[15:48:25.305] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:19:0)
[15:48:25.305] Timer tick, asking Buildbucket for the build status
[15:48:25.649] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:48:25.649] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:20:0) after 1m0s
[15:49:25.750] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:20:0)
[15:49:25.750] Timer tick, asking Buildbucket for the build status
[15:49:26.078] Build 8894537124812333632: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[15:49:26.079] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:21:0) after 1m0s
[15:49:33.457] Received PubSub notification, asking Buildbucket for the build status
[15:49:33.699] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:26.099] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:21:0)
[15:50:26.099] Timer tick, asking Buildbucket for the build status
[15:50:27.109] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:50:27.109] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:23:0) after 1m0s
[15:51:27.197] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:23:0)
[15:51:27.197] Timer tick, asking Buildbucket for the build status
[15:51:27.674] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:51:27.674] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:24:0) after 1m0s
[15:52:27.733] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:24:0)
[15:52:27.733] Timer tick, asking Buildbucket for the build status
[15:52:27.935] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:52:27.935] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:25:0) after 1m0s
[15:53:28.064] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:25:0)
[15:53:28.064] Timer tick, asking Buildbucket for the build status
[15:53:28.264] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:53:28.264] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:26:0) after 1m0s
[15:54:28.706] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:26:0)
[15:54:28.707] Timer tick, asking Buildbucket for the build status
[15:54:28.905] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:54:28.905] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:27:0) after 1m0s
[15:55:29.192] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:27:0)
[15:55:29.192] Timer tick, asking Buildbucket for the build status
[15:55:30.221] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:55:30.221] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:28:0) after 1m0s
[15:56:30.329] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:28:0)
[15:56:30.329] Timer tick, asking Buildbucket for the build status
[15:56:30.742] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:56:30.742] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:29:0) after 1m0s
[15:57:30.862] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:29:0)
[15:57:30.862] Timer tick, asking Buildbucket for the build status
[15:57:31.115] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:57:31.115] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:30:0) after 1m0s
[15:58:31.228] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:30:0)
[15:58:31.228] Timer tick, asking Buildbucket for the build status
[15:58:31.587] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:58:31.587] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:31:0) after 1m0s
[15:59:31.757] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:31:0)
[15:59:31.757] Timer tick, asking Buildbucket for the build status
[15:59:31.946] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[15:59:31.946] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:32:0) after 1m0s
[16:00:31.993] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:32:0)
[16:00:31.993] Timer tick, asking Buildbucket for the build status
[16:00:32.610] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:00:32.610] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:33:0) after 1m0s
[16:01:32.704] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:33:0)
[16:01:32.704] Timer tick, asking Buildbucket for the build status
[16:01:32.995] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:01:32.995] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:34:0) after 1m0s
[16:02:33.047] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:34:0)
[16:02:33.047] Timer tick, asking Buildbucket for the build status
[16:02:33.755] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:02:33.755] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:35:0) after 1m0s
[16:03:33.865] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:35:0)
[16:03:33.865] Timer tick, asking Buildbucket for the build status
[16:03:34.078] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:03:34.078] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:36:0) after 1m0s
[16:04:34.121] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:36:0)
[16:04:34.121] Timer tick, asking Buildbucket for the build status
[16:04:34.456] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:04:34.456] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:37:0) after 1m0s
[16:05:34.552] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:37:0)
[16:05:34.552] Timer tick, asking Buildbucket for the build status
[16:05:34.696] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:05:34.696] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:38:0) after 1m0s
[16:06:34.986] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:38:0)
[16:06:34.986] Timer tick, asking Buildbucket for the build status
[16:06:35.126] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:06:35.126] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:39:0) after 1m0s
[16:07:35.172] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:39:0)
[16:07:35.172] Timer tick, asking Buildbucket for the build status
[16:07:35.368] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:07:35.368] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:40:0) after 1m0s
[16:08:35.428] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:40:0)
[16:08:35.428] Timer tick, asking Buildbucket for the build status
[16:08:35.873] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:08:35.873] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:41:0) after 1m0s
[16:09:35.947] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:41:0)
[16:09:35.947] Timer tick, asking Buildbucket for the build status
[16:09:38.963] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:09:38.963] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:42:0) after 1m0s
[16:10:38.965] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:42:0)
[16:10:38.965] Timer tick, asking Buildbucket for the build status
[16:10:39.094] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:10:39.094] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:43:0) after 1m0s
[16:11:39.118] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:43:0)
[16:11:39.118] Timer tick, asking Buildbucket for the build status
[16:11:39.383] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:11:39.383] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:44:0) after 1m0s
[16:12:39.410] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:44:0)
[16:12:39.410] Timer tick, asking Buildbucket for the build status
[16:12:39.556] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:12:39.556] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:45:0) after 1m0s
[16:13:39.579] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:45:0)
[16:13:39.579] Timer tick, asking Buildbucket for the build status
[16:13:40.222] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:13:40.222] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:46:0) after 1m0s
[16:14:40.352] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:46:0)
[16:14:40.352] Timer tick, asking Buildbucket for the build status
[16:14:40.638] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:14:40.638] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:47:0) after 1m0s
[16:15:40.704] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:47:0)
[16:15:40.704] Timer tick, asking Buildbucket for the build status
[16:15:41.027] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:15:41.027] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:48:0) after 1m0s
[16:16:41.203] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:48:0)
[16:16:41.203] Timer tick, asking Buildbucket for the build status
[16:16:41.367] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:16:41.367] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:49:0) after 1m0s
[16:17:41.479] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:49:0)
[16:17:41.479] Timer tick, asking Buildbucket for the build status
[16:17:41.956] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:17:41.956] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:50:0) after 1m0s
[16:18:42.303] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:50:0)
[16:18:42.303] Timer tick, asking Buildbucket for the build status
[16:18:44.672] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:18:44.672] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:51:0) after 1m0s
[16:19:44.710] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:51:0)
[16:19:44.710] Timer tick, asking Buildbucket for the build status
[16:19:46.730] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:19:46.730] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:52:0) after 1m0s
[16:20:46.857] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:52:0)
[16:20:46.857] Timer tick, asking Buildbucket for the build status
[16:20:47.013] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:20:47.013] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:53:0) after 1m0s
[16:21:47.051] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:53:0)
[16:21:47.051] Timer tick, asking Buildbucket for the build status
[16:21:47.333] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:21:47.333] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:54:0) after 1m0s
[16:22:47.393] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:54:0)
[16:22:47.393] Timer tick, asking Buildbucket for the build status
[16:22:47.573] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:22:47.573] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:55:0) after 1m0s
[16:23:47.635] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:55:0)
[16:23:47.635] Timer tick, asking Buildbucket for the build status
[16:23:47.806] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:23:47.806] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:56:0) after 1m0s
[16:24:47.832] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:56:0)
[16:24:47.832] Timer tick, asking Buildbucket for the build status
[16:24:48.365] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:24:48.365] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:57:0) after 1m0s
[16:25:48.505] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:57:0)
[16:25:48.505] Timer tick, asking Buildbucket for the build status
[16:25:49.125] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:25:49.125] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:58:0) after 1m0s
[16:26:49.189] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:58:0)
[16:26:49.189] Timer tick, asking Buildbucket for the build status
[16:26:50.256] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:26:50.256] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:59:0) after 1m0s
[16:27:50.371] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:59:0)
[16:27:50.371] Timer tick, asking Buildbucket for the build status
[16:27:50.812] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:27:50.812] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:60:0) after 1m0s
[16:28:50.882] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:60:0)
[16:28:50.882] Timer tick, asking Buildbucket for the build status
[16:28:51.085] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:28:51.085] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:61:0) after 1m0s
[16:29:51.215] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:61:0)
[16:29:51.215] Timer tick, asking Buildbucket for the build status
[16:29:51.523] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:29:51.523] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:62:0) after 1m0s
[16:30:51.690] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:62:0)
[16:30:51.690] Timer tick, asking Buildbucket for the build status
[16:30:51.925] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:30:51.925] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:63:0) after 1m0s
[16:31:52.094] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:63:0)
[16:31:52.094] Timer tick, asking Buildbucket for the build status
[16:31:52.422] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:31:52.422] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:64:0) after 1m0s
[16:32:52.644] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:64:0)
[16:32:52.644] Timer tick, asking Buildbucket for the build status
[16:32:54.089] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:32:54.089] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:65:0) after 1m0s
[16:33:54.526] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:65:0)
[16:33:54.526] Timer tick, asking Buildbucket for the build status
[16:33:56.448] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:33:56.448] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:66:0) after 1m0s
[16:34:56.611] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:66:0)
[16:34:56.611] Timer tick, asking Buildbucket for the build status
[16:34:57.560] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:34:57.560] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:67:0) after 1m0s
[16:35:57.590] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:67:0)
[16:35:57.590] Timer tick, asking Buildbucket for the build status
[16:35:58.196] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:35:58.196] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:68:0) after 1m0s
[16:36:58.227] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:68:0)
[16:36:58.227] Timer tick, asking Buildbucket for the build status
[16:36:58.495] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:36:58.495] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:69:0) after 1m0s
[16:37:58.655] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:69:0)
[16:37:58.655] Timer tick, asking Buildbucket for the build status
[16:37:59.796] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:37:59.796] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:70:0) after 1m0s
[16:38:59.823] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:70:0)
[16:38:59.823] Timer tick, asking Buildbucket for the build status
[16:39:00.391] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:39:00.391] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:71:0) after 1m0s
[16:40:00.536] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:71:0)
[16:40:00.536] Timer tick, asking Buildbucket for the build status
[16:40:00.851] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:40:00.851] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:72:0) after 1m0s
[16:41:02.311] Handling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:72:0)
[16:41:02.311] Timer tick, asking Buildbucket for the build status
[16:41:02.572] Build 8894537124812333632: status "STARTED", result "", failure_reason "", cancelation_reason ""
[16:41:02.572] Scheduling timer "check-buildbucket-build-status" (dart/vm-ffi-android-debug-arm:9059967196103283184:73:0) after 1m0s
[16:41:52.184] Received PubSub notification, asking Buildbucket for the build status
[16:41:52.329] Build 8894537124812333632: status "COMPLETED", result "SUCCESS", failure_reason "", cancelation_reason ""
[16:41:52.329] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"completed_ts": "1575909711062223",
"created_by": "project:dart",
"created_ts": "1575905409952751",
"id": "8894537124812333632",
"parameters_json": "{\"builder_name\": \"vm-ffi-android-debug-arm\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"title\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"url\": \"https://dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}}",
"project": "dart",
"result": "SUCCESS",
"result_details_json": "{\"properties\": {\"$depot_tools/osx_sdk\": {\"sdk_version\": \"9c40b\"}, \"$recipe_engine/path\": {\"cache_dir\": \"/b/s/w/ir/cache\", \"temp_dir\": \"/b/s/w/ir/tmp/rt\"}, \"$recipe_engine/runtime\": {\"is_experimental\": false, \"is_luci\": true}, \"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"title\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"url\": \"https://dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}]}, \"bot_id\": \"luci-dart-try-xenial-33-y8cn\", \"branch\": \"refs/heads/master\", \"buildername\": \"vm-ffi-android-debug-arm\", \"buildnumber\": 2662, \"clobber\": \"true\", \"got_revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\", \"new_workflow_enabled\": true, \"path_config\": \"generic\", \"recipe\": \"dart/neo\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"16e7647c8618a4ab7f66753a3e1774e11f40ef68\"}, \"swarming\": {\"bot_dimensions\": {\"caches\": [\"swarming_module_cache_vpython\"], \"cores\": [\"8\"], \"cpu\": [\"x86\", \"x86-64\", \"x86-64-Broadwell_GCE\", \"x86-64-avx2\"], \"gce\": [\"1\"], \"gpu\": [\"none\"], \"host_class\": [\"default\"], \"id\": [\"luci-dart-try-xenial-33-y8cn\"], \"image\": [\"chrome-xenial-19120800-09d8782d9a1\"], \"inside_docker\": [\"0\"], \"kvm\": [\"1\"], \"locale\": [\"en_US.UTF-8\"], \"machine_type\": [\"n1-standard-8\"], \"os\": [\"Linux\", \"Ubuntu\", \"Ubuntu-16.04\"], \"pool\": [\"dart.tests\", \"luci.dart.try\"], \"python\": [\"2.7.12\"], \"server_version\": [\"4702-f6d98f9\"], \"ssd\": [\"0\"], \"zone\": [\"us\", \"us-central\", \"us-central1\", \"us-central1-b\"]}}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1575906572165309",
"status": "COMPLETED",
"status_changed_ts": "1575909711602330",
"tags": [
"build_address:luci.dart.ci.sandbox/vm-ffi-android-debug-arm/2662",
"builder:vm-ffi-android-debug-arm",
"buildset:commit/git/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/16e7647c8618a4ab7f66753a3e1774e11f40ef68",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9059967196103283184",
"scheduler_job_id:dart/vm-ffi-android-debug-arm",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8894537124812333632/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:os:Linux",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:infra/recipe_bundles/chromium.googlesource.com/chromium/tools/build",
"swarming_task_id:490419c918f0c110",
"user_agent:luci-scheduler"
],
"updated_ts": "1575909711617824",
"url": "https://ci.chromium.org/b/8894537124812333632",
"utcnow_ts": "1575909712314877"
}
}
[16:41:52.330] Invocation finished in 1h11m52.528998084s with status SUCCEEDED