[14:03:52.066] New invocation is queued and will start shortly
[14:03:53.231] Starting the invocation (attempt 1)
[14:03:53.273] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/d1098f6275a64a13219ccdedc971a04447a3f8b1"
[14:03:53.273] Popped gitiles commit info from properties and tags
[14:03:53.273] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[14:03:53.273] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[14:03:53.273] Buildbucket request:
{
"requestId": "8979975493550034624",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1098f6275a64a13219ccdedc971a04447a3f8b1",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8979975493550034624"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Android (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[14:03:53.974] Scheduled build:
{
"id": "8814545431534746097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 10797,
"createdBy": "project:chromium",
"createTime": "2022-05-10T14:03:53.357291953Z",
"updateTime": "2022-05-10T14:03:53.357291953Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1098f6275a64a13219ccdedc971a04447a3f8b1",
"ref": "refs/heads/main"
}
}
}
[14:03:53.974] Task URL: https://cr-buildbucket.appspot.com/build/8814545431534746097
[14:03:53.974] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:2:0) after 1m28s
[14:04:31.983] Received PubSub notification, asking Buildbucket for the build status
[14:04:32.007] Build status: STARTED
[14:05:22.078] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:2:0)
[14:05:22.103] Build status: STARTED
[14:05:22.103] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:4:0) after 1m40s
[14:07:02.121] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:4:0)
[14:07:02.143] Build status: STARTED
[14:07:02.143] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:5:0) after 4m22s
[14:11:24.491] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:5:0)
[14:11:24.513] Build status: STARTED
[14:11:24.513] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:6:0) after 1m58s
[14:13:22.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:6:0)
[14:13:22.623] Build status: STARTED
[14:13:22.623] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:7:0) after 6m46s
[14:20:08.676] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:7:0)
[14:20:08.726] Build status: STARTED
[14:20:08.726] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:8:0) after 6m3s
[14:26:11.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:8:0)
[14:26:11.755] Build status: STARTED
[14:26:11.755] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:9:0) after 5m52s
[14:32:03.780] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:9:0)
[14:32:03.838] Build status: STARTED
[14:32:03.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:10:0) after 6m14s
[14:38:17.807] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:10:0)
[14:38:17.827] Build status: STARTED
[14:38:17.827] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:11:0) after 3m33s
[14:41:51.157] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:11:0)
[14:41:51.251] Build status: STARTED
[14:41:51.251] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:12:0) after 7m52s
[14:49:43.379] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:12:0)
[14:49:43.398] Build status: STARTED
[14:49:43.398] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:13:0) after 7m18s
[14:57:01.721] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:13:0)
[14:57:01.758] Build status: STARTED
[14:57:01.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:14:0) after 9m6s
[15:06:07.936] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:14:0)
[15:06:07.965] Build status: STARTED
[15:06:07.965] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:15:0) after 5m11s
[15:11:19.177] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:15:0)
[15:11:19.202] Build status: STARTED
[15:11:19.202] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:16:0) after 3m34s
[15:14:53.322] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:16:0)
[15:14:53.350] Build status: STARTED
[15:14:53.350] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:17:0) after 3m36s
[15:18:29.462] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:17:0)
[15:18:29.485] Build status: STARTED
[15:18:29.485] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:18:0) after 3m48s
[15:22:17.655] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:18:0)
[15:22:17.707] Build status: STARTED
[15:22:17.707] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:19:0) after 8m12s
[15:30:29.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:19:0)
[15:30:29.762] Build status: STARTED
[15:30:29.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:20:0) after 8m13s
[15:38:42.797] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:20:0)
[15:38:42.818] Build status: STARTED
[15:38:42.818] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:21:0) after 1m29s
[15:40:11.906] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:21:0)
[15:40:11.936] Build status: STARTED
[15:40:11.936] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:22:0) after 3m13s
[15:43:25.964] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:22:0)
[15:43:25.986] Build status: STARTED
[15:43:25.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:23:0) after 4m22s
[15:47:48.015] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:23:0)
[15:47:48.035] Build status: STARTED
[15:47:48.035] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:24:0) after 6m0s
[15:53:48.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:24:0)
[15:53:48.092] Build status: STARTED
[15:53:48.092] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:25:0) after 8m17s
[16:02:05.114] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:25:0)
[16:02:05.161] Build status: STARTED
[16:02:05.161] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:26:0) after 5m8s
[16:07:13.169] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:26:0)
[16:07:13.190] Build status: STARTED
[16:07:13.190] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:27:0) after 9m20s
[16:16:33.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:27:0)
[16:16:33.275] Build status: STARTED
[16:16:33.275] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:28:0) after 1m40s
[16:18:13.296] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:28:0)
[16:18:13.343] Build status: STARTED
[16:18:13.343] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:29:0) after 3m44s
[16:21:57.353] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:29:0)
[16:21:57.401] Build status: STARTED
[16:21:57.401] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:30:0) after 9m32s
[16:31:29.666] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:30:0)
[16:31:29.709] Build status: STARTED
[16:31:29.709] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:31:0) after 8m14s
[16:39:43.728] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:31:0)
[16:39:43.751] Build status: STARTED
[16:39:43.751] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:32:0) after 9m56s
[16:49:40.074] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:32:0)
[16:49:40.107] Build status: STARTED
[16:49:40.107] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:33:0) after 2m9s
[16:51:49.160] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:33:0)
[16:51:49.204] Build status: STARTED
[16:51:49.204] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:34:0) after 2m15s
[16:54:04.223] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:34:0)
[16:54:04.245] Build status: STARTED
[16:54:04.245] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:35:0) after 4m11s
[16:58:15.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:35:0)
[16:58:15.546] Build status: STARTED
[16:58:15.546] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:36:0) after 3m27s
[17:01:42.678] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:36:0)
[17:01:42.743] Build status: STARTED
[17:01:42.743] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:37:0) after 6m8s
[17:07:50.847] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:37:0)
[17:07:50.883] Build status: STARTED
[17:07:50.883] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8979975493550034624:38:0) after 7m58s
[17:12:54.664] Received PubSub notification, asking Buildbucket for the build status
[17:12:54.740] Build:
{
"id": "8814545431534746097",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 10797,
"createdBy": "project:chromium",
"createTime": "2022-05-10T14:03:53.357291953Z",
"startTime": "2022-05-10T14:04:31.304833Z",
"endTime": "2022-05-10T17:12:54.232024827Z",
"updateTime": "2022-05-10T17:12:54.232024827Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "d1098f6275a64a13219ccdedc971a04447a3f8b1",
"ref": "refs/heads/main"
}
}
}
[17:12:54.740] Invocation finished in 3h9m2.689009187s with status SUCCEEDED