[17:34:02.304] New invocation is queued and will start shortly
[17:34:03.542] Starting the invocation (attempt 1)
[17:34:03.581] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:34:03.581] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:34:03.581] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9073548937052933920",
"parameters_json": "{\"builder_name\":\"analyzer-mac-release\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"gitiles\":{\"ref\":\"refs/heads/master\",\"repo\":\"https://dart.googlesource.com/sdk\",\"revision\":\"8b012ac780c04ef13d99cce785a0541510961707\"},\"id\":\"https://dart.googlesource.com/sdk/+/refs/heads/master@8b012ac780c04ef13d99cce785a0541510961707\",\"title\":\"8b012ac780c04ef13d99cce785a0541510961707\",\"url\":\"https://dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707\"}]},\"branch\":\"refs/heads/master\",\"repository\":\"https://dart.googlesource.com/sdk\",\"revision\":\"8b012ac780c04ef13d99cce785a0541510961707\"}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:analyzer-mac-release",
"scheduler_invocation_id:9073548937052933920",
"scheduler_job_id:dart/analyzer-mac-release",
"user_agent:luci-scheduler",
"buildset:commit/git/8b012ac780c04ef13d99cce785a0541510961707",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707",
"gitiles_ref:refs/heads/master"
]
}
[17:34:06.147] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1562952843898098",
"id": "8908118874714703216",
"parameters_json": "{\"builder_name\": \"analyzer-mac-release\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"8b012ac780c04ef13d99cce785a0541510961707\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@8b012ac780c04ef13d99cce785a0541510961707\", \"title\": \"8b012ac780c04ef13d99cce785a0541510961707\", \"url\": \"https://dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"8b012ac780c04ef13d99cce785a0541510961707\"}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1562952844809111",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-mac-release/6132",
"builder:analyzer-mac-release",
"buildset:commit/git/8b012ac780c04ef13d99cce785a0541510961707",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073548937052933920",
"scheduler_job_id:dart/analyzer-mac-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8908118874714703216/+/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": "1562952844809213",
"url": "https://ci.chromium.org/b/8908118874714703216",
"utcnow_ts": "1562952846115330"
}
}
[17:34:06.147] Task URL: https://ci.chromium.org/b/8908118874714703216
[17:34:06.147] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:2:0) after 1m0s
[17:35:06.166] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:2:0)
[17:35:06.166] Timer tick, asking Buildbucket for the build status
[17:35:07.639] Build 8908118874714703216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:35:07.639] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:3:0) after 1m0s
[17:36:07.706] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:3:0)
[17:36:07.706] Timer tick, asking Buildbucket for the build status
[17:36:08.366] Build 8908118874714703216: status "SCHEDULED", result "", failure_reason "", cancelation_reason ""
[17:36:08.366] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:4:0) after 1m0s
[17:36:55.725] Received PubSub notification, asking Buildbucket for the build status
[17:36:57.355] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:08.425] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:4:0)
[17:37:08.425] Timer tick, asking Buildbucket for the build status
[17:37:08.875] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:37:08.875] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:6:0) after 1m0s
[17:38:08.996] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:6:0)
[17:38:08.996] Timer tick, asking Buildbucket for the build status
[17:38:09.927] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:38:09.927] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:7:0) after 1m0s
[17:39:09.955] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:7:0)
[17:39:09.955] Timer tick, asking Buildbucket for the build status
[17:39:10.837] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:39:10.837] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:8:0) after 1m0s
[17:40:10.859] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:8:0)
[17:40:10.859] Timer tick, asking Buildbucket for the build status
[17:40:11.083] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:40:11.083] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:9:0) after 1m0s
[17:41:11.223] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:9:0)
[17:41:11.223] Timer tick, asking Buildbucket for the build status
[17:41:11.806] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:41:11.806] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:10:0) after 1m0s
[17:42:11.826] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:10:0)
[17:42:11.826] Timer tick, asking Buildbucket for the build status
[17:42:12.917] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:42:12.917] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:11:0) after 1m0s
[17:43:12.969] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:11:0)
[17:43:12.969] Timer tick, asking Buildbucket for the build status
[17:43:13.438] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:43:13.438] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:12:0) after 1m0s
[17:44:13.536] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:12:0)
[17:44:13.536] Timer tick, asking Buildbucket for the build status
[17:44:14.120] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:44:14.120] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:13:0) after 1m0s
[17:45:14.117] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:13:0)
[17:45:14.117] Timer tick, asking Buildbucket for the build status
[17:45:14.305] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:45:14.305] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:14:0) after 1m0s
[17:46:14.409] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:14:0)
[17:46:14.409] Timer tick, asking Buildbucket for the build status
[17:46:14.823] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:46:14.823] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:15:0) after 1m0s
[17:47:15.022] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:15:0)
[17:47:15.023] Timer tick, asking Buildbucket for the build status
[17:47:15.240] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:47:15.240] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:16:0) after 1m0s
[17:48:15.300] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:16:0)
[17:48:15.300] Timer tick, asking Buildbucket for the build status
[17:48:16.403] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:48:16.403] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:17:0) after 1m0s
[17:49:16.538] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:17:0)
[17:49:16.538] Timer tick, asking Buildbucket for the build status
[17:49:16.715] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:49:16.715] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:18:0) after 1m0s
[17:50:16.744] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:18:0)
[17:50:16.744] Timer tick, asking Buildbucket for the build status
[17:50:17.245] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:50:17.245] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:19:0) after 1m0s
[17:51:17.252] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:19:0)
[17:51:17.252] Timer tick, asking Buildbucket for the build status
[17:51:19.133] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:51:19.133] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:20:0) after 1m0s
[17:52:19.173] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:20:0)
[17:52:19.173] Timer tick, asking Buildbucket for the build status
[17:52:22.509] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:52:22.509] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:21:0) after 1m0s
[17:53:22.567] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:21:0)
[17:53:22.567] Timer tick, asking Buildbucket for the build status
[17:53:22.975] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:53:22.975] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:22:0) after 1m0s
[17:54:23.000] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:22:0)
[17:54:23.000] Timer tick, asking Buildbucket for the build status
[17:54:23.139] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:54:23.139] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:23:0) after 1m0s
[17:55:23.201] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:23:0)
[17:55:23.201] Timer tick, asking Buildbucket for the build status
[17:55:23.339] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:55:23.339] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:24:0) after 1m0s
[17:56:23.363] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:24:0)
[17:56:23.363] Timer tick, asking Buildbucket for the build status
[17:56:23.549] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:56:23.549] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:25:0) after 1m0s
[17:57:23.581] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:25:0)
[17:57:23.581] Timer tick, asking Buildbucket for the build status
[17:57:23.973] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:57:23.973] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:26:0) after 1m0s
[17:58:24.004] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:26:0)
[17:58:24.004] Timer tick, asking Buildbucket for the build status
[17:58:24.186] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:58:24.186] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:27:0) after 1m0s
[17:59:24.218] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:27:0)
[17:59:24.218] Timer tick, asking Buildbucket for the build status
[17:59:24.463] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[17:59:24.463] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:28:0) after 1m0s
[18:00:24.521] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:28:0)
[18:00:24.521] Timer tick, asking Buildbucket for the build status
[18:00:24.948] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:00:24.948] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:29:0) after 1m0s
[18:01:25.287] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:29:0)
[18:01:25.287] Timer tick, asking Buildbucket for the build status
[18:01:25.467] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:01:25.467] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:30:0) after 1m0s
[18:02:25.483] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:30:0)
[18:02:25.483] Timer tick, asking Buildbucket for the build status
[18:02:25.773] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:02:25.773] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:31:0) after 1m0s
[18:03:25.789] Handling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:31:0)
[18:03:25.789] Timer tick, asking Buildbucket for the build status
[18:03:25.968] Build 8908118874714703216: status "STARTED", result "", failure_reason "", cancelation_reason ""
[18:03:25.968] Scheduling timer "check-buildbucket-build-status" (dart/analyzer-mac-release:9073548937052933920:32:0) after 1m0s
[18:03:28.394] Received PubSub notification, asking Buildbucket for the build status
[18:03:28.588] Build 8908118874714703216: status "COMPLETED", result "FAILURE", failure_reason "BUILD_FAILURE", cancelation_reason ""
[18:03:28.588] Buildbucket build:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"completed_ts": "1562954607880717",
"created_by": "project:dart",
"created_ts": "1562952843898098",
"failure_reason": "BUILD_FAILURE",
"id": "8908118874714703216",
"parameters_json": "{\"builder_name\": \"analyzer-mac-release\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"gitiles\": {\"ref\": \"refs/heads/master\", \"repo\": \"https://dart.googlesource.com/sdk\", \"revision\": \"8b012ac780c04ef13d99cce785a0541510961707\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@8b012ac780c04ef13d99cce785a0541510961707\", \"title\": \"8b012ac780c04ef13d99cce785a0541510961707\", \"url\": \"https://dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707\"}]}, \"branch\": \"refs/heads/master\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"8b012ac780c04ef13d99cce785a0541510961707\"}}",
"project": "dart",
"result": "FAILURE",
"result_details_json": "{\"error\": {\"message\": \"1 out of 2 aggregated steps failed: 1 out of 1 aggregated steps failed: Step('test results') (retcode: 1)\"}, \"properties\": {\"$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\": \"8b012ac780c04ef13d99cce785a0541510961707\"}, \"id\": \"https://dart.googlesource.com/sdk/+/refs/heads/master@8b012ac780c04ef13d99cce785a0541510961707\", \"title\": \"8b012ac780c04ef13d99cce785a0541510961707\", \"url\": \"https://dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707\"}]}, \"bot_id\": \"vm15-m9\", \"branch\": \"refs/heads/master\", \"buildername\": \"analyzer-mac-release\", \"buildnumber\": 6132, \"clobber\": \"true\", \"got_revision\": \"8b012ac780c04ef13d99cce785a0541510961707\", \"new_workflow_enabled\": true, \"path_config\": \"generic\", \"recipe\": \"dart/neo\", \"repository\": \"https://dart.googlesource.com/sdk\", \"revision\": \"8b012ac780c04ef13d99cce785a0541510961707\"}, \"ui\": {\"info\": \"1 out of 2 aggregated steps failed: 1 out of 1 aggregated steps failed: Step('test results') (retcode: 1)\"}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"started_ts": "1562953015139390",
"status": "COMPLETED",
"status_changed_ts": "1562952844809111",
"tags": [
"build_address:luci.dart.ci.sandbox/analyzer-mac-release/6132",
"builder:analyzer-mac-release",
"buildset:commit/git/8b012ac780c04ef13d99cce785a0541510961707",
"buildset:commit/gitiles/dart.googlesource.com/sdk/+/8b012ac780c04ef13d99cce785a0541510961707",
"gitiles_ref:refs/heads/master",
"scheduler_invocation_id:9073548937052933920",
"scheduler_job_id:dart/analyzer-mac-release",
"swarming_hostname:chromium-swarm.appspot.com",
"swarming_tag:log_location:logdog://logs.chromium.org/dart/buildbucket/cr-buildbucket.appspot.com/8908118874714703216/+/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:460011510d8de110",
"user_agent:luci-scheduler"
],
"updated_ts": "1562954608008843",
"url": "https://ci.chromium.org/b/8908118874714703216",
"utcnow_ts": "1562954608576432"
}
}
[18:03:28.588] Invocation finished in 29m26.302527522s with status FAILED