[07:35:36.611] New invocation is queued and will start shortly
[07:35:37.771] Starting the invocation (attempt 1)
[07:35:37.804] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/9ac925a1f6a8374de393ee366d38822b0c56c3b0"
[07:35:37.804] Popped gitiles commit info from properties and tags
[07:35:37.804] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[07:35:37.804] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[07:35:37.804] Buildbucket request:
{
"requestId": "9010984083113239216",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ac925a1f6a8374de393ee366d38822b0c56c3b0",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9010984083113239216"
},
{
"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"
}
}
[07:35:38.450] Scheduled build:
{
"id": "8845554021107744816",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 9227,
"createdBy": "project:chromium",
"createTime": "2021-06-02T07:35:37.893973Z",
"updateTime": "2021-06-02T07:35:38.310273Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ac925a1f6a8374de393ee366d38822b0c56c3b0",
"ref": "refs/heads/master"
}
}
}
[07:35:38.450] Task URL: https://cr-buildbucket.appspot.com/build/8845554021107744816
[07:35:38.450] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:2:0) after 8m17s
[07:36:04.246] Received PubSub notification, asking Buildbucket for the build status
[07:36:04.273] Build status: STARTED
[07:43:55.473] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:2:0)
[07:43:55.520] Build status: STARTED
[07:43:55.520] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:4:0) after 5m53s
[07:49:48.640] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:4:0)
[07:49:48.667] Build status: STARTED
[07:49:48.667] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:5:0) after 7m30s
[07:57:18.797] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:5:0)
[07:57:18.822] Build status: STARTED
[07:57:18.822] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:6:0) after 4m57s
[08:02:16.010] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:6:0)
[08:02:16.048] Build status: STARTED
[08:02:16.048] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:7:0) after 1m2s
[08:03:18.169] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:7:0)
[08:03:18.201] Build status: STARTED
[08:03:18.201] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:8:0) after 7m3s
[08:10:21.371] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:8:0)
[08:10:21.399] Build status: STARTED
[08:10:21.399] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:9:0) after 4m21s
[08:14:42.422] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:9:0)
[08:14:42.447] Build status: STARTED
[08:14:42.447] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:10:0) after 2m56s
[08:17:38.470] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:10:0)
[08:17:38.503] Build status: STARTED
[08:17:38.503] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:11:0) after 4m18s
[08:21:56.537] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:11:0)
[08:21:56.577] Build status: STARTED
[08:21:56.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:12:0) after 5m15s
[08:27:11.618] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:12:0)
[08:27:11.653] Build status: STARTED
[08:27:11.653] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:13:0) after 2m9s
[08:29:20.705] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:13:0)
[08:29:20.734] Build status: STARTED
[08:29:20.734] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:14:0) after 5m0s
[08:34:20.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:14:0)
[08:34:20.892] Build status: STARTED
[08:34:20.892] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:15:0) after 1m1s
[08:35:21.883] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:15:0)
[08:35:21.914] Build status: STARTED
[08:35:21.914] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:16:0) after 8m23s
[08:43:45.467] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:16:0)
[08:43:45.499] Build status: STARTED
[08:43:45.499] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:17:0) after 5m34s
[08:49:19.832] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:17:0)
[08:49:19.858] Build status: STARTED
[08:49:19.858] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:18:0) after 9m57s
[08:59:17.101] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:18:0)
[08:59:17.136] Build status: STARTED
[08:59:17.136] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:19:0) after 8m56s
[09:08:13.273] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:19:0)
[09:08:13.306] Build status: STARTED
[09:08:13.306] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:20:0) after 8m26s
[09:16:39.519] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:20:0)
[09:16:39.548] Build status: STARTED
[09:16:39.548] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:21:0) after 7m51s
[09:24:30.570] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:21:0)
[09:24:30.608] Build status: STARTED
[09:24:30.608] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:22:0) after 1m25s
[09:25:55.735] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:22:0)
[09:25:55.765] Build status: STARTED
[09:25:55.765] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:23:0) after 5m33s
[09:31:28.900] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:23:0)
[09:31:28.928] Build status: STARTED
[09:31:28.928] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:24:0) after 3m31s
[09:34:59.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:24:0)
[09:34:59.976] Build status: STARTED
[09:34:59.976] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:25:0) after 3m22s
[09:38:22.037] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:25:0)
[09:38:22.070] Build status: STARTED
[09:38:22.070] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:26:0) after 4m47s
[09:43:09.164] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:26:0)
[09:43:09.194] Build status: STARTED
[09:43:09.194] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:27:0) after 2m32s
[09:45:41.268] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:27:0)
[09:45:41.298] Build status: STARTED
[09:45:41.299] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:28:0) after 8m10s
[09:53:51.320] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:28:0)
[09:53:51.345] Build status: STARTED
[09:53:51.345] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:29:0) after 7m33s
[10:01:24.452] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:29:0)
[10:01:24.487] Build status: STARTED
[10:01:24.487] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:30:0) after 2m50s
[10:04:14.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:30:0)
[10:04:14.791] Build status: STARTED
[10:04:14.791] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:31:0) after 3m25s
[10:07:39.816] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:31:0)
[10:07:39.904] Build status: STARTED
[10:07:39.904] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:32:0) after 1m12s
[10:08:52.026] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:32:0)
[10:08:52.058] Build status: STARTED
[10:08:52.058] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:33:0) after 7m58s
[10:16:50.132] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:33:0)
[10:16:50.159] Build status: STARTED
[10:16:50.159] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:34:0) after 8m13s
[10:25:03.222] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:34:0)
[10:25:03.250] Build status: STARTED
[10:25:03.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:35:0) after 8m40s
[10:33:43.765] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:35:0)
[10:33:43.794] Build status: STARTED
[10:33:43.794] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:36:0) after 7m8s
[10:40:51.816] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:36:0)
[10:40:51.841] Build status: STARTED
[10:40:51.841] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:37:0) after 6m37s
[10:47:29.087] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:37:0)
[10:47:29.126] Build status: STARTED
[10:47:29.126] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:38:0) after 3m17s
[10:50:46.208] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:38:0)
[10:50:46.243] Build status: STARTED
[10:50:46.243] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:39:0) after 8m36s
[10:59:22.377] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:39:0)
[10:59:22.403] Build status: STARTED
[10:59:22.403] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:40:0) after 8m55s
[11:08:17.538] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:40:0)
[11:08:17.575] Build status: STARTED
[11:08:17.575] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:41:0) after 9m4s
[11:17:21.616] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:41:0)
[11:17:21.644] Build status: STARTED
[11:17:21.644] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:42:0) after 8m31s
[11:25:52.671] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:42:0)
[11:25:56.640] Build status: STARTED
[11:25:56.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:43:0) after 4m28s
[11:30:24.741] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:43:0)
[11:30:24.787] Build status: STARTED
[11:30:24.787] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:44:0) after 6m10s
[11:36:34.988] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:44:0)
[11:36:35.017] Build status: STARTED
[11:36:35.017] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:45:0) after 1m56s
[11:38:31.040] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:45:0)
[11:38:31.068] Build status: STARTED
[11:38:31.068] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:46:0) after 1m3s
[11:39:34.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:46:0)
[11:39:34.128] Build status: STARTED
[11:39:34.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010984083113239216:47:0) after 9m8s
[11:45:20.624] Received PubSub notification, asking Buildbucket for the build status
[11:45:20.654] Build:
{
"id": "8845554021107744816",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 9227,
"createdBy": "project:chromium",
"createTime": "2021-06-02T07:35:37.893973Z",
"startTime": "2021-06-02T07:36:03.378191Z",
"endTime": "2021-06-02T11:45:20.261372779Z",
"updateTime": "2021-06-02T11:45:20.264418210Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "9ac925a1f6a8374de393ee366d38822b0c56c3b0",
"ref": "refs/heads/master"
}
}
}
[11:45:20.654] Invocation finished in 4h9m44.057691449s with status SUCCEEDED