[05:51:52.960] New invocation is queued and will start shortly
[05:51:54.234] Starting the invocation (attempt 1)
[05:51:54.261] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/edc9e328503033934a42716a48ed8f4e9bc83166"
[05:51:54.261] Popped gitiles commit info from properties and tags
[05:51:54.261] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:51:54.263] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:51:54.263] Buildbucket request:
{
"requestId": "8929272145390197472",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edc9e328503033934a42716a48ed8f4e9bc83166",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8929272145390197472"
},
{
"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"
}
}
[05:51:55.079] Scheduled build:
{
"id": "8763842083216180769",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 14791,
"createdBy": "project:chromium",
"createTime": "2023-11-21T05:51:54.404987004Z",
"updateTime": "2023-11-21T05:51:54.404987004Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edc9e328503033934a42716a48ed8f4e9bc83166",
"ref": "refs/heads/main"
}
}
}
[05:51:55.079] Task URL: https://cr-buildbucket.appspot.com/build/8763842083216180769
[05:51:55.079] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:2:0) after 9m0s
[05:51:55.978] Received PubSub notification, asking Buildbucket for the build status
[05:51:56.000] Build status: SCHEDULED
[05:51:56.477] Received PubSub notification, asking Buildbucket for the build status
[05:51:56.506] Build status: STARTED
[06:00:55.206] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:2:0)
[06:00:55.231] Build status: STARTED
[06:00:55.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:5:0) after 1m24s
[06:02:19.247] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:5:0)
[06:02:19.268] Build status: STARTED
[06:02:19.268] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:6:0) after 3m2s
[06:05:21.368] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:6:0)
[06:05:21.432] Build status: STARTED
[06:05:21.433] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:7:0) after 6m17s
[06:11:38.602] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:7:0)
[06:11:38.618] Build status: STARTED
[06:11:38.618] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:8:0) after 6m45s
[06:18:23.680] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:8:0)
[06:18:23.704] Build status: STARTED
[06:18:23.704] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:9:0) after 7m43s
[06:26:06.743] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:9:0)
[06:26:06.766] Build status: STARTED
[06:26:06.766] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:10:0) after 5m30s
[06:31:36.879] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:10:0)
[06:31:37.026] Build status: STARTED
[06:31:37.026] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:11:0) after 9m22s
[06:40:59.110] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:11:0)
[06:40:59.133] Build status: STARTED
[06:40:59.133] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:12:0) after 5m21s
[06:46:20.160] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:12:0)
[06:46:20.195] Build status: STARTED
[06:46:20.195] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:13:0) after 5m1s
[06:51:21.301] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:13:0)
[06:51:21.331] Build status: STARTED
[06:51:21.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:14:0) after 6m43s
[06:58:04.475] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:14:0)
[06:58:04.498] Build status: STARTED
[06:58:04.498] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:15:0) after 1m43s
[06:59:47.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:15:0)
[06:59:47.576] Build status: STARTED
[06:59:47.576] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:16:0) after 2m27s
[07:02:14.708] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:16:0)
[07:02:14.747] Build status: STARTED
[07:02:14.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:17:0) after 5m58s
[07:08:12.767] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:17:0)
[07:08:12.799] Build status: STARTED
[07:08:12.799] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:18:0) after 8m36s
[07:16:48.985] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:18:0)
[07:16:49.012] Build status: STARTED
[07:16:49.012] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:19:0) after 3m11s
[07:20:00.196] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:19:0)
[07:20:00.220] Build status: STARTED
[07:20:00.220] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:20:0) after 1m45s
[07:21:45.239] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:20:0)
[07:21:45.259] Build status: STARTED
[07:21:45.259] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:21:0) after 9m18s
[07:31:03.285] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:21:0)
[07:31:03.304] Build status: STARTED
[07:31:03.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:22:0) after 9m3s
[07:40:06.324] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:22:0)
[07:40:06.345] Build status: STARTED
[07:40:06.345] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:23:0) after 1m42s
[07:41:48.456] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:23:0)
[07:41:48.511] Build status: STARTED
[07:41:48.511] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:24:0) after 7m31s
[07:49:19.950] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:24:0)
[07:49:19.968] Build status: STARTED
[07:49:19.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:25:0) after 8m55s
[07:58:15.002] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:25:0)
[07:58:15.130] Build status: STARTED
[07:58:15.130] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:26:0) after 9m3s
[08:07:18.322] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:26:0)
[08:07:18.380] Build status: STARTED
[08:07:18.380] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:27:0) after 6m35s
[08:13:53.433] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:27:0)
[08:13:53.474] Build status: STARTED
[08:13:53.474] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:28:0) after 3m19s
[08:17:12.876] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:28:0)
[08:17:12.916] Build status: STARTED
[08:17:12.916] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:29:0) after 3m2s
[08:20:15.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:29:0)
[08:20:15.084] Build status: STARTED
[08:20:15.084] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:30:0) after 6m14s
[08:26:29.204] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:30:0)
[08:26:29.250] Build status: STARTED
[08:26:29.250] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:31:0) after 5m53s
[08:32:22.725] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:31:0)
[08:32:22.744] Build status: STARTED
[08:32:22.744] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:32:0) after 7m14s
[08:39:36.866] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:32:0)
[08:39:36.920] Build status: STARTED
[08:39:36.920] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:33:0) after 6m19s
[08:45:56.039] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:33:0)
[08:45:56.163] Build status: STARTED
[08:45:56.163] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:34:0) after 7m56s
[08:53:52.212] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:34:0)
[08:53:52.230] Build status: STARTED
[08:53:52.230] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:35:0) after 2m32s
[08:56:24.265] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:35:0)
[08:56:24.312] Build status: STARTED
[08:56:24.312] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:36:0) after 4m46s
[09:01:10.596] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:36:0)
[09:01:10.624] Build status: STARTED
[09:01:10.624] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:37:0) after 2m57s
[09:04:07.687] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:37:0)
[09:04:07.719] Build status: STARTED
[09:04:07.719] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:38:0) after 9m30s
[09:13:37.739] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:38:0)
[09:13:37.819] Build status: STARTED
[09:13:37.819] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:39:0) after 1m9s
[09:14:46.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:39:0)
[09:14:46.873] Build status: STARTED
[09:14:46.873] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:40:0) after 7m51s
[09:22:38.013] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:40:0)
[09:22:38.033] Build status: STARTED
[09:22:38.033] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:41:0) after 5m17s
[09:27:55.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:41:0)
[09:27:55.213] Build status: STARTED
[09:27:55.213] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:42:0) after 3m1s
[09:30:56.332] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:42:0)
[09:30:56.414] Build status: STARTED
[09:30:56.414] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:43:0) after 6m32s
[09:37:28.659] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:43:0)
[09:37:28.701] Build status: STARTED
[09:37:28.701] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:44:0) after 3m2s
[09:40:30.727] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:44:0)
[09:40:30.754] Build status: STARTED
[09:40:30.754] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):8929272145390197472:45:0) after 2m19s
[09:40:47.461] Received PubSub notification, asking Buildbucket for the build status
[09:40:47.580] Build:
{
"id": "8763842083216180769",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 14791,
"createdBy": "project:chromium",
"createTime": "2023-11-21T05:51:54.404987004Z",
"startTime": "2023-11-21T05:51:55.543431Z",
"endTime": "2023-11-21T09:40:46.399479Z",
"updateTime": "2023-11-21T09:40:46.399479Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "edc9e328503033934a42716a48ed8f4e9bc83166",
"ref": "refs/heads/main"
}
}
}
[09:40:47.580] Invocation finished in 3h48m54.633690148s with status SUCCEEDED