[03:37:31.014] New invocation is queued and will start shortly
[03:37:32.134] Starting the invocation (attempt 1)
[03:37:32.180] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/5ef9bf6c17a0af493a447af3e798d93c6d647d36"
[03:37:32.180] Popped gitiles commit info from properties and tags
[03:37:32.180] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[03:37:32.180] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[03:37:32.180] Buildbucket request:
{
"requestId": "8987353254417792736",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ef9bf6c17a0af493a447af3e798d93c6d647d36",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8987353254417792736"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[03:37:32.730] Scheduled build:
{
"id": "8821923192433552849",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"number": 32737,
"createdBy": "project:chromium",
"createTime": "2022-02-18T03:37:32.275532075Z",
"updateTime": "2022-02-18T03:37:32.275532075Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ef9bf6c17a0af493a447af3e798d93c6d647d36",
"ref": "refs/heads/main"
}
}
}
[03:37:32.730] Task URL: https://cr-buildbucket.appspot.com/build/8821923192433552849
[03:37:32.730] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:2:0) after 4m31s
[03:37:33.995] Received PubSub notification, asking Buildbucket for the build status
[03:37:34.017] Build status: STARTED
[03:42:03.920] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:2:0)
[03:42:03.947] Build status: STARTED
[03:42:03.947] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:4:0) after 3m2s
[03:45:06.133] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:4:0)
[03:45:06.167] Build status: STARTED
[03:45:06.167] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:5:0) after 1m17s
[03:46:23.272] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:5:0)
[03:46:23.304] Build status: STARTED
[03:46:23.304] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:6:0) after 7m59s
[03:54:22.425] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:6:0)
[03:54:22.451] Build status: STARTED
[03:54:22.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:7:0) after 4m13s
[03:58:35.555] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:7:0)
[03:58:35.602] Build status: STARTED
[03:58:35.602] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:8:0) after 6m19s
[04:04:54.628] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:8:0)
[04:04:54.658] Build status: STARTED
[04:04:54.658] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:9:0) after 8m45s
[04:13:39.681] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:9:0)
[04:13:39.762] Build status: STARTED
[04:13:39.762] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:10:0) after 7m12s
[04:20:51.858] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:10:0)
[04:20:51.899] Build status: STARTED
[04:20:51.899] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:11:0) after 4m41s
[04:25:32.967] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:11:0)
[04:25:32.986] Build status: STARTED
[04:25:32.986] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:12:0) after 5m53s
[04:31:26.069] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:12:0)
[04:31:26.090] Build status: STARTED
[04:31:26.090] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:13:0) after 8m36s
[04:40:02.224] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:13:0)
[04:40:02.242] Build status: STARTED
[04:40:02.242] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:14:0) after 4m53s
[04:44:56.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:14:0)
[04:44:56.097] Build status: STARTED
[04:44:56.097] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:15:0) after 3m18s
[04:48:14.210] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:15:0)
[04:48:14.246] Build status: STARTED
[04:48:14.246] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:16:0) after 6m58s
[04:55:12.334] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:16:0)
[04:55:12.367] Build status: STARTED
[04:55:12.367] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:17:0) after 4m39s
[04:59:51.386] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:17:0)
[04:59:51.477] Build status: STARTED
[04:59:51.477] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:18:0) after 8m23s
[05:08:14.617] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:18:0)
[05:08:14.636] Build status: STARTED
[05:08:14.636] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:19:0) after 5m0s
[05:13:14.739] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:19:0)
[05:13:14.793] Build status: STARTED
[05:13:14.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:20:0) after 9m44s
[05:22:58.815] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:20:0)
[05:22:58.847] Build status: STARTED
[05:22:58.847] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:21:0) after 7m23s
[05:30:21.844] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:21:0)
[05:30:21.882] Build status: STARTED
[05:30:21.882] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:22:0) after 6m43s
[05:37:05.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:22:0)
[05:37:05.265] Build status: STARTED
[05:37:05.265] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:23:0) after 1m58s
[05:39:03.288] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:23:0)
[05:39:03.482] Build status: STARTED
[05:39:03.482] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:24:0) after 2m8s
[05:41:11.520] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:24:0)
[05:41:11.542] Build status: STARTED
[05:41:11.542] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:25:0) after 6m5s
[05:47:16.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:25:0)
[05:47:16.706] Build status: STARTED
[05:47:16.706] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:26:0) after 3m27s
[05:50:43.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:26:0)
[05:50:43.757] Build status: STARTED
[05:50:43.757] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:27:0) after 2m54s
[05:53:38.080] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:27:0)
[05:53:38.118] Build status: STARTED
[05:53:38.118] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:28:0) after 4m37s
[05:58:15.291] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:28:0)
[05:58:15.344] Build status: STARTED
[05:58:15.344] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:29:0) after 3m48s
[06:02:03.319] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:29:0)
[06:02:03.356] Build status: STARTED
[06:02:03.356] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:30:0) after 5m2s
[06:07:05.394] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:30:0)
[06:07:05.549] Build status: STARTED
[06:07:05.549] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:31:0) after 5m20s
[06:12:25.690] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:31:0)
[06:12:25.769] Build status: STARTED
[06:12:25.769] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:32:0) after 5m34s
[06:17:59.787] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:32:0)
[06:17:59.808] Build status: STARTED
[06:17:59.808] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:33:0) after 6m28s
[06:24:28.070] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:33:0)
[06:24:28.094] Build status: STARTED
[06:24:28.094] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:34:0) after 9m55s
[06:34:23.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:34:0)
[06:34:23.162] Build status: STARTED
[06:34:23.162] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:35:0) after 4m45s
[06:39:08.198] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:35:0)
[06:39:08.225] Build status: STARTED
[06:39:08.225] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:36:0) after 1m22s
[06:40:30.278] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:36:0)
[06:40:30.297] Build status: STARTED
[06:40:30.297] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:37:0) after 3m32s
[06:44:02.322] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:37:0)
[06:44:02.341] Build status: STARTED
[06:44:02.341] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:38:0) after 4m25s
[06:48:27.375] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:38:0)
[06:48:27.409] Build status: STARTED
[06:48:27.409] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:39:0) after 2m42s
[06:51:09.431] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:39:0)
[06:51:09.458] Build status: STARTED
[06:51:09.458] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:40:0) after 8m50s
[06:59:59.712] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:40:0)
[06:59:59.736] Build status: STARTED
[06:59:59.736] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:41:0) after 2m22s
[07:02:21.760] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:41:0)
[07:02:21.804] Build status: STARTED
[07:02:21.804] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux:8987353254417792736:42:0) after 6m50s
[07:03:53.540] Received PubSub notification, asking Buildbucket for the build status
[07:03:53.572] Build:
{
"id": "8821923192433552849",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux"
},
"number": 32737,
"createdBy": "project:chromium",
"createTime": "2022-02-18T03:37:32.275532075Z",
"startTime": "2022-02-18T03:37:33.366556Z",
"endTime": "2022-02-18T07:03:53.116573853Z",
"updateTime": "2022-02-18T07:03:53.116573853Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "5ef9bf6c17a0af493a447af3e798d93c6d647d36",
"ref": "refs/heads/main"
}
}
}
[07:03:53.572] Invocation finished in 3h26m22.573356229s with status SUCCEEDED