[12:44:35.014] New invocation is queued and will start shortly
[12:44:36.259] Starting the invocation (attempt 1)
[12:44:36.283] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/4b2ea7aa6485149261695dc848eba984691d9766"
[12:44:36.283] Popped gitiles commit info from properties and tags
[12:44:36.283] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[12:44:36.283] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[12:44:36.283] Buildbucket request:
{
"requestId": "8908227684288125104",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b2ea7aa6485149261695dc848eba984691d9766",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8908227684288125104"
},
{
"key": "scheduler_job_id",
"value": "chromium/fuchsia-x64-cast-receiver-dbg"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[12:44:36.928] Scheduled build:
{
"id": "8742797622233597329",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 1493,
"createdBy": "project:chromium",
"createTime": "2024-07-10T12:44:36.346855329Z",
"updateTime": "2024-07-10T12:44:36.346855329Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b2ea7aa6485149261695dc848eba984691d9766",
"ref": "refs/heads/main"
}
}
}
[12:44:36.928] Task URL: https://cr-buildbucket.appspot.com/build/8742797622233597329
[12:44:36.928] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:2:0) after 7m32s
[12:44:37.790] Received PubSub notification, asking Buildbucket for the build status
[12:44:37.806] Build status: SCHEDULED
[12:52:08.944] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:2:0)
[12:52:08.964] Build status: SCHEDULED
[12:52:08.964] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:4:0) after 6m7s
[12:58:15.981] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:4:0)
[12:58:16.013] Build status: SCHEDULED
[12:58:16.013] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:5:0) after 3m5s
[13:01:21.458] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:5:0)
[13:01:21.482] Build status: SCHEDULED
[13:01:21.482] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:6:0) after 3m5s
[13:04:26.499] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:6:0)
[13:04:26.517] Build status: SCHEDULED
[13:04:26.517] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:7:0) after 2m19s
[13:06:45.593] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:7:0)
[13:06:45.613] Build status: SCHEDULED
[13:06:45.613] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:8:0) after 1m49s
[13:08:34.792] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:8:0)
[13:08:34.811] Build status: SCHEDULED
[13:08:34.811] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:9:0) after 1m40s
[13:10:14.895] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:9:0)
[13:10:14.945] Build status: SCHEDULED
[13:10:14.945] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:10:0) after 4m37s
[13:14:52.034] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:10:0)
[13:14:52.051] Build status: SCHEDULED
[13:14:52.051] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:11:0) after 4m26s
[13:16:04.051] Received PubSub notification, asking Buildbucket for the build status
[13:16:04.067] Build status: STARTED
[13:19:18.283] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:11:0)
[13:19:18.303] Build status: STARTED
[13:19:18.303] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:13:0) after 9m47s
[13:29:05.373] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:13:0)
[13:29:05.434] Build status: STARTED
[13:29:05.434] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:14:0) after 5m0s
[13:34:05.654] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:14:0)
[13:34:05.724] Build status: STARTED
[13:34:05.724] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:15:0) after 4m8s
[13:38:13.712] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:15:0)
[13:38:13.734] Build status: STARTED
[13:38:13.734] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:16:0) after 7m48s
[13:46:01.714] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:16:0)
[13:46:01.768] Build status: STARTED
[13:46:01.768] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:17:0) after 2m37s
[13:48:39.014] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:17:0)
[13:48:39.032] Build status: STARTED
[13:48:39.032] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:18:0) after 4m9s
[13:52:48.067] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:18:0)
[13:52:48.194] Build status: STARTED
[13:52:48.194] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:19:0) after 7m32s
[14:00:20.361] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:19:0)
[14:00:20.387] Build status: STARTED
[14:00:20.387] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:20:0) after 5m53s
[14:06:13.397] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:20:0)
[14:06:13.417] Build status: STARTED
[14:06:13.417] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:21:0) after 1m45s
[14:07:58.444] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:21:0)
[14:07:58.472] Build status: STARTED
[14:07:58.472] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:22:0) after 8m56s
[14:16:54.718] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:22:0)
[14:16:54.794] Build status: STARTED
[14:16:54.794] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:23:0) after 4m56s
[14:21:50.864] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:23:0)
[14:21:50.882] Build status: STARTED
[14:21:50.883] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:24:0) after 6m2s
[14:27:52.904] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:24:0)
[14:27:52.926] Build status: STARTED
[14:27:52.926] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:25:0) after 6m50s
[14:34:42.943] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:25:0)
[14:34:42.965] Build status: STARTED
[14:34:42.965] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:26:0) after 4m37s
[14:39:20.506] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:26:0)
[14:39:20.527] Build status: STARTED
[14:39:20.527] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:27:0) after 9m32s
[14:48:52.548] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:27:0)
[14:48:52.569] Build status: STARTED
[14:48:52.569] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:28:0) after 1m27s
[14:50:19.597] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:28:0)
[14:50:19.629] Build status: STARTED
[14:50:19.629] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:29:0) after 2m1s
[14:52:20.730] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:29:0)
[14:52:20.747] Build status: STARTED
[14:52:20.747] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:30:0) after 4m17s
[14:56:38.429] Handling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:30:0)
[14:56:38.445] Build status: STARTED
[14:56:38.445] Scheduling timer "check-buildbucket-build-status" (chromium/fuchsia-x64-cast-receiver-dbg:8908227684288125104:31:0) after 2m26s
[14:58:25.459] Received PubSub notification, asking Buildbucket for the build status
[14:58:25.518] Build:
{
"id": "8742797622233597329",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "fuchsia-x64-cast-receiver-dbg"
},
"number": 1493,
"createdBy": "project:chromium",
"createTime": "2024-07-10T12:44:36.346855329Z",
"startTime": "2024-07-10T13:16:03.852095794Z",
"endTime": "2024-07-10T14:58:24.908488912Z",
"updateTime": "2024-07-10T14:58:24.908488912Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "4b2ea7aa6485149261695dc848eba984691d9766",
"ref": "refs/heads/main"
}
}
}
[14:58:25.518] Invocation finished in 2h13m50.514580645s with status SUCCEEDED