[04:05:14.907] New invocation is queued and will start shortly
[04:05:16.064] Starting the invocation (attempt 1)
[04:05:16.098] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:05:16.098] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:05:16.098] Buildbucket request:
{
"bucket": "luci.dart.ci.sandbox",
"client_operation_id": "9017520299510940944",
"parameters_json": "{\"builder_name\":\"fuzz-linux\",\"properties\":{\"$recipe_engine/scheduler\":{\"hostname\":\"luci-scheduler.appspot.com\",\"triggers\":[{\"cron\":{\"generation\":\"4428\"},\"id\":\"cron:v1:4428\"}]}}}",
"pubsub_callback": {
"auth_token": "...",
"topic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
},
"tags": [
"builder:fuzz-linux",
"scheduler_invocation_id:9017520299510940944",
"scheduler_job_id:dart/fuzz-linux",
"user_agent:luci-scheduler"
]
}
[04:05:16.785] Buildbucket response:
{
"build": {
"bucket": "luci.dart.ci.sandbox",
"canary_preference": "PROD",
"created_by": "project:dart",
"created_ts": "1616385916182513",
"id": "8852090237512244912",
"parameters_json": "{\"builder_name\": \"fuzz-linux\", \"properties\": {\"$recipe_engine/scheduler\": {\"hostname\": \"luci-scheduler.appspot.com\", \"triggers\": [{\"cron\": {\"generation\": \"4428\"}, \"id\": \"cron:v1:4428\"}]}}}",
"project": "dart",
"result_details_json": "{\"properties\": {}}",
"service_account": "dart-luci-try-builder@dart-ci.iam.gserviceaccount.com",
"status": "SCHEDULED",
"status_changed_ts": "1616385916578177",
"tags": [
"build_address:luci.dart.ci.sandbox/fuzz-linux/1477",
"builder:fuzz-linux",
"scheduler_invocation_id:9017520299510940944",
"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/8852090237512244912/+/annotations",
"swarming_tag:luci_project:dart",
"swarming_tag:recipe_name:dart/neo",
"swarming_tag:recipe_package:dart/recipe_bundles/dart.googlesource.com/recipes",
"swarming_task_id:",
"user_agent:luci-scheduler"
],
"updated_ts": "1616385916578314",
"url": "https://ci.chromium.org/b/8852090237512244912",
"utcnow_ts": "1616385916777779"
}
}
[04:05:16.785] Task URL: https://ci.chromium.org/b/8852090237512244912
[04:05:16.785] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:2:0) after 1m0s
[04:05:18.953] Received PubSub notification, asking Buildbucket for the build status
[04:05:18.984] Build status: STARTED
[04:06:16.801] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:2:0)
[04:06:16.825] Build status: STARTED
[04:06:16.825] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:4:0) after 6m50s
[04:13:06.857] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:4:0)
[04:13:06.857] Timer tick, asking Buildbucket for the build status
[04:13:06.947] Build 8852090237512244912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:13:06.947] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:5:0) after 1m0s
[04:14:06.965] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:5:0)
[04:14:06.989] Build status: STARTED
[04:14:06.989] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:6:0) after 1m39s
[04:15:46.008] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:6:0)
[04:15:46.037] Build status: STARTED
[04:15:46.037] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:7:0) after 6m14s
[04:22:00.225] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:7:0)
[04:22:00.293] Build status: STARTED
[04:22:00.294] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:8:0) after 7m33s
[04:29:33.310] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:8:0)
[04:29:33.336] Build status: STARTED
[04:29:33.336] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:9:0) after 5m20s
[04:34:53.524] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:9:0)
[04:34:53.603] Build status: STARTED
[04:34:53.603] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:10:0) after 2m13s
[04:37:06.620] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:10:0)
[04:37:06.646] Build status: STARTED
[04:37:06.646] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:11:0) after 1m48s
[04:38:54.664] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:11:0)
[04:38:54.664] Timer tick, asking Buildbucket for the build status
[04:38:54.762] Build 8852090237512244912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:38:54.762] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:12:0) after 1m0s
[04:39:54.776] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:12:0)
[04:39:54.776] Timer tick, asking Buildbucket for the build status
[04:39:54.843] Build 8852090237512244912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:39:54.843] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:13:0) after 1m0s
[04:40:54.895] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:13:0)
[04:40:54.895] Timer tick, asking Buildbucket for the build status
[04:40:55.061] Build 8852090237512244912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:40:55.061] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:14:0) after 1m0s
[04:41:55.205] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:14:0)
[04:41:55.205] Timer tick, asking Buildbucket for the build status
[04:41:55.298] Build 8852090237512244912: status "STARTED", result "", failure_reason "", cancelation_reason ""
[04:41:55.298] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:15:0) after 1m0s
[04:42:55.326] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:15:0)
[04:42:55.359] Build status: STARTED
[04:42:55.359] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:16:0) after 7m21s
[04:50:16.585] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:16:0)
[04:50:16.624] Build status: STARTED
[04:50:16.624] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:17:0) after 6m41s
[04:56:57.656] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:17:0)
[04:56:57.684] Build status: STARTED
[04:56:57.684] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:18:0) after 5m17s
[05:02:14.799] Handling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:18:0)
[05:02:14.824] Build status: STARTED
[05:02:14.824] Scheduling timer "check-buildbucket-build-status" (dart/fuzz-linux:9017520299510940944:19:0) after 1m8s
[05:03:00.218] Received PubSub notification, asking Buildbucket for the build status
[05:03:00.256] Build:
{
"id": "8852090237512244912",
"builder": {
"project": "dart",
"bucket": "ci.sandbox",
"builder": "fuzz-linux"
},
"number": 1477,
"createdBy": "project:dart",
"createTime": "2021-03-22T04:05:16.182513Z",
"startTime": "2021-03-22T04:05:18.220139Z",
"endTime": "2021-03-22T05:02:59.530112Z",
"updateTime": "2021-03-22T05:02:59.960688Z",
"status": "SUCCESS",
"input": {
}
}
[05:03:00.256] Invocation finished in 57m45.363472849s with status SUCCEEDED