[03:00:05.666] New invocation is queued and will start shortly
[03:00:15.783] Starting the invocation (attempt 1)
[03:00:15.824] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:00:15.824] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:00:15.824] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9060104972858781936",
"parameters_json": "{\"builder_name\":\"fuzz-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"1605\"},\"id\":\"cron:v1:1605\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:fuzz-linux",
"scheduler_invocation_id:9060104972858781936",
"scheduler_job_id:dart/fuzz-linux",
"user_agent:luci-scheduler"
]
}
[03:00:16.818] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1575774016069343",
"id": "8894674901284576528",
"parameters_json": "{\"builder_name\": \"fuzz-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"1605\"}, \"id\": \"cron:v1:1605\"}]}}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1575774016574165",
"tags": [
"build_address:luci.dart.ci.sandbox/fuzz-linux/536",
"builder:fuzz-linux",
"scheduler_invocation_id:9060104972858781936",
"scheduler_job_id:dart/fuzz-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8894674901284576528/+/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": "1575774016574387",
"url": "https://ci.chromium.org/b/8894674901284576528",
"utcnow_ts": "1575774016811539"
}
}
[03:00:16.818] Task URL: https://ci.chromium.org/b/8894674901284576528
[03:00:16.818] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:2:0) after 1m0s
[03:00:57.502] Received PubSub notification, asking Buildbucket for the build status
[03:00:57.725] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:16.979] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:2:0)
[03:01:16.979] Timer tick, asking Buildbucket for the build status
[03:01:17.141] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:01:17.141] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:4:0) after 1m0s
[03:02:17.181] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:4:0)
[03:02:17.181] Timer tick, asking Buildbucket for the build status
[03:02:17.912] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:02:17.912] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:5:0) after 1m0s
[03:03:17.995] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:5:0)
[03:03:17.995] Timer tick, asking Buildbucket for the build status
[03:03:18.169] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:03:18.169] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:6:0) after 1m0s
[03:04:18.328] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:6:0)
[03:04:18.328] Timer tick, asking Buildbucket for the build status
[03:04:19.377] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:04:19.377] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:7:0) after 1m0s
[03:05:19.499] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:7:0)
[03:05:19.499] Timer tick, asking Buildbucket for the build status
[03:05:20.336] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:05:20.336] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:8:0) after 1m0s
[03:06:20.475] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:8:0)
[03:06:20.475] Timer tick, asking Buildbucket for the build status
[03:06:20.635] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:06:20.635] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:9:0) after 1m0s
[03:07:20.788] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:9:0)
[03:07:20.788] Timer tick, asking Buildbucket for the build status
[03:07:21.182] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:07:21.182] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:10:0) after 1m0s
[03:08:21.257] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:10:0)
[03:08:21.257] Timer tick, asking Buildbucket for the build status
[03:08:21.469] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:08:21.469] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:11:0) after 1m0s
[03:09:21.639] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:11:0)
[03:09:21.639] Timer tick, asking Buildbucket for the build status
[03:09:21.955] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:09:21.955] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:12:0) after 1m0s
[03:10:22.134] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:12:0)
[03:10:22.134] Timer tick, asking Buildbucket for the build status
[03:10:22.401] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:10:22.402] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:13:0) after 1m0s
[03:11:22.478] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:13:0)
[03:11:22.478] Timer tick, asking Buildbucket for the build status
[03:11:23.689] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:11:23.689] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:14:0) after 1m0s
[03:12:23.676] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:14:0)
[03:12:23.676] Timer tick, asking Buildbucket for the build status
[03:12:23.923] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:12:23.923] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:15:0) after 1m0s
[03:13:23.900] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:15:0)
[03:13:23.900] Timer tick, asking Buildbucket for the build status
[03:13:24.113] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:13:24.113] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:16:0) after 1m0s
[03:14:24.189] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:16:0)
[03:14:24.189] Timer tick, asking Buildbucket for the build status
[03:14:24.789] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:14:24.789] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:17:0) after 1m0s
[03:15:24.813] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:17:0)
[03:15:24.813] Timer tick, asking Buildbucket for the build status
[03:15:24.983] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:15:24.983] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:18:0) after 1m0s
[03:16:25.105] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:18:0)
[03:16:25.105] Timer tick, asking Buildbucket for the build status
[03:16:25.307] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:16:25.307] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:19:0) after 1m0s
[03:17:25.392] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:19:0)
[03:17:25.392] Timer tick, asking Buildbucket for the build status
[03:17:25.949] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:17:25.949] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:20:0) after 1m0s
[03:18:26.025] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:20:0)
[03:18:26.025] Timer tick, asking Buildbucket for the build status
[03:18:26.189] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:18:26.189] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:21:0) after 1m0s
[03:19:26.266] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:21:0)
[03:19:26.266] Timer tick, asking Buildbucket for the build status
[03:19:26.499] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:19:26.499] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:22:0) after 1m0s
[03:20:26.596] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:22:0)
[03:20:26.596] Timer tick, asking Buildbucket for the build status
[03:20:26.887] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:20:26.887] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:23:0) after 1m0s
[03:21:26.911] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:23:0)
[03:21:26.911] Timer tick, asking Buildbucket for the build status
[03:21:27.639] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:21:27.639] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:24:0) after 1m0s
[03:22:27.725] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:24:0)
[03:22:27.725] Timer tick, asking Buildbucket for the build status
[03:22:28.272] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:22:28.272] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:25:0) after 1m0s
[03:23:28.299] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:25:0)
[03:23:28.299] Timer tick, asking Buildbucket for the build status
[03:23:30.074] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:23:30.074] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:26:0) after 1m0s
[03:24:30.108] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:26:0)
[03:24:30.108] Timer tick, asking Buildbucket for the build status
[03:24:30.410] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:24:30.410] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:27:0) after 1m0s
[03:25:30.672] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:27:0)
[03:25:30.672] Timer tick, asking Buildbucket for the build status
[03:25:31.455] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:25:31.455] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:28:0) after 1m0s
[03:26:31.449] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:28:0)
[03:26:31.449] Timer tick, asking Buildbucket for the build status
[03:26:31.872] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:26:31.872] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:29:0) after 1m0s
[03:27:31.901] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:29:0)
[03:27:31.901] Timer tick, asking Buildbucket for the build status
[03:27:33.289] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:27:33.289] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:30:0) after 1m0s
[03:28:33.367] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:30:0)
[03:28:33.367] Timer tick, asking Buildbucket for the build status
[03:28:33.590] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:28:33.590] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:31:0) after 1m0s
[03:29:33.611] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:31:0)
[03:29:33.611] Timer tick, asking Buildbucket for the build status
[03:29:33.784] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:29:33.784] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:32:0) after 1m0s
[03:30:33.863] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:32:0)
[03:30:33.863] Timer tick, asking Buildbucket for the build status
[03:30:34.385] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:30:34.385] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:33:0) after 1m0s
[03:31:34.413] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:33:0)
[03:31:34.413] Timer tick, asking Buildbucket for the build status
[03:31:34.812] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:31:34.812] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:34:0) after 1m0s
[03:32:34.900] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:34:0)
[03:32:34.900] Timer tick, asking Buildbucket for the build status
[03:32:35.179] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:32:35.179] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:35:0) after 1m0s
[03:33:35.206] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:35:0)
[03:33:35.206] Timer tick, asking Buildbucket for the build status
[03:33:35.925] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:33:35.925] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:36:0) after 1m0s
[03:34:36.128] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:36:0)
[03:34:36.128] Timer tick, asking Buildbucket for the build status
[03:34:36.549] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:34:36.549] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:37:0) after 1m0s
[03:35:36.632] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:37:0)
[03:35:36.632] Timer tick, asking Buildbucket for the build status
[03:35:37.264] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:35:37.264] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:38:0) after 1m0s
[03:36:37.290] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:38:0)
[03:36:37.290] Timer tick, asking Buildbucket for the build status
[03:36:37.828] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:36:37.828] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:39:0) after 1m0s
[03:37:37.904] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:39:0)
[03:37:37.904] Timer tick, asking Buildbucket for the build status
[03:37:38.086] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:37:38.086] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:40:0) after 1m0s
[03:38:38.112] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:40:0)
[03:38:38.112] Timer tick, asking Buildbucket for the build status
[03:38:38.399] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:38:38.399] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:41:0) after 1m0s
[03:39:38.401] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:41:0)
[03:39:38.401] Timer tick, asking Buildbucket for the build status
[03:39:38.529] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:39:38.529] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:42:0) after 1m0s
[03:40:38.560] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:42:0)
[03:40:38.560] Timer tick, asking Buildbucket for the build status
[03:40:38.784] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:40:38.784] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:43:0) after 1m0s
[03:41:38.811] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:43:0)
[03:41:38.811] Timer tick, asking Buildbucket for the build status
[03:41:38.966] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:41:38.966] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:44:0) after 1m0s
[03:42:38.994] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:44:0)
[03:42:38.994] Timer tick, asking Buildbucket for the build status
[03:42:39.223] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:42:39.223] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:45:0) after 1m0s
[03:43:39.245] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:45:0)
[03:43:39.245] Timer tick, asking Buildbucket for the build status
[03:43:39.474] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:43:39.474] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:46:0) after 1m0s
[03:44:39.500] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:46:0)
[03:44:39.500] Timer tick, asking Buildbucket for the build status
[03:44:39.699] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:44:39.699] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:47:0) after 1m0s
[03:45:39.723] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:47:0)
[03:45:39.723] Timer tick, asking Buildbucket for the build status
[03:45:39.905] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:45:39.905] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:48:0) after 1m0s
[03:46:39.928] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:48:0)
[03:46:39.928] Timer tick, asking Buildbucket for the build status
[03:46:42.692] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:46:42.692] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:49:0) after 1m0s
[03:47:42.723] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:49:0)
[03:47:42.723] Timer tick, asking Buildbucket for the build status
[03:47:42.894] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:47:42.894] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:50:0) after 1m0s
[03:48:42.968] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:50:0)
[03:48:42.968] Timer tick, asking Buildbucket for the build status
[03:48:43.214] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:48:43.214] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:51:0) after 1m0s
[03:49:43.259] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:51:0)
[03:49:43.259] Timer tick, asking Buildbucket for the build status
[03:49:43.467] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:49:43.467] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:52:0) after 1m0s
[03:50:43.540] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:52:0)
[03:50:43.540] Timer tick, asking Buildbucket for the build status
[03:50:43.771] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:50:43.771] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:53:0) after 1m0s
[03:51:43.861] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:53:0)
[03:51:43.861] Timer tick, asking Buildbucket for the build status
[03:51:44.547] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:51:44.547] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:54:0) after 1m0s
[03:52:44.612] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:54:0)
[03:52:44.612] Timer tick, asking Buildbucket for the build status
[03:52:44.811] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:52:44.811] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:55:0) after 1m0s
[03:53:44.837] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:55:0)
[03:53:44.837] Timer tick, asking Buildbucket for the build status
[03:53:45.313] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:53:45.313] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:56:0) after 1m0s
[03:54:45.339] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:56:0)
[03:54:45.339] Timer tick, asking Buildbucket for the build status
[03:54:45.537] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:54:45.537] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:57:0) after 1m0s
[03:55:45.608] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:57:0)
[03:55:45.608] Timer tick, asking Buildbucket for the build status
[03:55:45.854] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:55:45.854] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:58:0) after 1m0s
[03:56:45.875] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:58:0)
[03:56:45.875] Timer tick, asking Buildbucket for the build status
[03:56:46.034] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:56:46.034] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:59:0) after 1m0s
[03:57:46.069] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:59:0)
[03:57:46.069] Timer tick, asking Buildbucket for the build status
[03:57:46.271] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:57:46.271] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:60:0) after 1m0s
[03:58:46.326] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:60:0)
[03:58:46.326] Timer tick, asking Buildbucket for the build status
[03:58:46.552] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:58:46.552] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:61:0) after 1m0s
[03:59:46.583] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:61:0)
[03:59:46.583] Timer tick, asking Buildbucket for the build status
[03:59:46.941] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[03:59:46.941] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:62:0) after 1m0s
[04:00:46.950] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:62:0)
[04:00:46.950] Timer tick, asking Buildbucket for the build status
[04:00:47.118] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:00:47.118] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:63:0) after 1m0s
[04:01:47.144] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:63:0)
[04:01:47.144] Timer tick, asking Buildbucket for the build status
[04:01:47.452] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:01:47.452] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:64:0) after 1m0s
[04:02:47.538] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:64:0)
[04:02:47.538] Timer tick, asking Buildbucket for the build status
[04:02:48.216] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:02:48.216] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:65:0) after 1m0s
[04:03:48.241] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:65:0)
[04:03:48.241] Timer tick, asking Buildbucket for the build status
[04:03:48.561] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:03:48.561] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:66:0) after 1m0s
[04:04:48.585] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:66:0)
[04:04:48.585] Timer tick, asking Buildbucket for the build status
[04:04:48.993] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:04:48.993] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:67:0) after 1m0s
[04:05:49.027] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:67:0)
[04:05:49.027] Timer tick, asking Buildbucket for the build status
[04:05:49.839] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:05:49.839] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:68:0) after 1m0s
[04:06:49.855] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:68:0)
[04:06:49.855] Timer tick, asking Buildbucket for the build status
[04:06:50.088] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:06:50.089] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:69:0) after 1m0s
[04:07:50.118] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:69:0)
[04:07:50.118] Timer tick, asking Buildbucket for the build status
[04:07:50.733] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:07:50.733] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:70:0) after 1m0s
[04:08:50.787] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:70:0)
[04:08:50.787] Timer tick, asking Buildbucket for the build status
[04:08:51.003] Build 8894674901284576528: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:08:51.003] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9060104972858781936:71:0) after 1m0s
[04:09:38.487] Received PubSub notification, asking Buildbucket for the build status
[04:09:38.674] Build 8894674901284576528: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[04:09:38.674] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"completed_ts": "1575778158561143",
"created_by": "project:dart",
"created_ts": "1575774016069343",
"failure_reason": "BUILD_FAILURE",
"id": "8894674901284576528",
"parameters_json": "{\"builder_name\": \"fuzz-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"1605\"}, \"id\": \"cron:v1:1605\"}]}}}",
"project": "dart",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 out of 2 aggregated steps failed: 1 out of 5 aggregated steps failed: 31 out of 99 aggregated steps failed: Step(\\\"['shard #0 (failed) (46m 45s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 29s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 23s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 12s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 37s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 58s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 50s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 34s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 16s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 19s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 48s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 41s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 57s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 18s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 28s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 22s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 26s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 7s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 26s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 57s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 35s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 16s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 22s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 13s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 18s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 54s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 48s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 42s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 25s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 8s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 9s)']\\\") (retcode: 0)\"}, \"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\": [{\"cron\": {\"generation\": \"1605\"}, \"id\": \"cron:v1:1605\"}]}, \"bot_id\": \"luci-dart-try-xenial-46-gmtc\", \"buildername\": \"fuzz-linux\", \"buildnumber\": 536, \"clobber\": \"true\", \"got_revision\": \"eb862ca54dd427bedad7953a73be67b12b15b9bc\", \"path_config\": \"generic\", \"recipe\": \"dart/neo\"}, \"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-46-gmtc\"], \"image\": [\"chrome-xenial-19120100-f3a09baa543\"], \"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\"]}}, \"ui\": {\"info\": \"1 out of 2 aggregated steps failed: 1 out of 5 aggregated steps failed: 31 out of 99 aggregated steps failed: Step(\\\"['shard #0 (failed) (46m 45s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 29s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 23s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 12s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 37s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 58s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 50s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 34s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 16s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 19s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 48s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 41s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 57s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 18s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 28s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 22s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 26s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 7s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 26s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (48m 57s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 35s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 16s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 22s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 13s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 18s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 54s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (45m 48s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 42s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (47m 25s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 8s)']\\\") (retcode: 0), Step(\\\"['shard #0 (failed) (46m 9s)']\\\") (retcode: 0)\"}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1575774018258177",
"status": "COMPLETED",
"status_changed_ts": "1575774043681093",
"tags": [
"build_address:luci.dart.ci.sandbox/fuzz-linux/536",
"builder:fuzz-linux",
"scheduler_invocation_id:9060104972858781936",
"scheduler_job_id:dart/fuzz-linux",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8894674901284576528/+/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:48fc44dfa9029d10",
"user_agent:luci-scheduler"
],
"updated_ts": "1575778158818344",
"url": "https://ci.chromium.org/b/8894674901284576528",
"utcnow_ts": "1575778178658845"
}
}
[04:09:38.674] Invocation finished in 1h9m33.025939485s with status FAILED