[20:28:32.565] New invocation is queued and will start shortly
[20:28:33.682] Starting the invocation (attempt 1)
[20:28:33.719] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/a3ae77554afcde1a06823c7ccf70f4a6a1b49b0b"
[20:28:33.719] Popped gitiles commit info from properties and tags
[20:28:33.719] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[20:28:33.719] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[20:28:33.719] Buildbucket request:
{
"requestId": "8918345354732667728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3ae77554afcde1a06823c7ccf70f4a6a1b49b0b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8918345354732667728"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Fuchsia (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[20:28:34.386] Scheduled build:
{
"id": "8752915292785208193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 19650,
"createdBy": "project:chromium",
"createTime": "2024-03-20T20:28:33.799375799Z",
"updateTime": "2024-03-20T20:28:33.799375799Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3ae77554afcde1a06823c7ccf70f4a6a1b49b0b",
"ref": "refs/heads/main"
}
}
}
[20:28:34.386] Task URL: https://cr-buildbucket.appspot.com/build/8752915292785208193
[20:28:34.386] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:2:0) after 2m43s
[20:28:35.373] Received PubSub notification, asking Buildbucket for the build status
[20:28:35.401] Build status: SCHEDULED
[20:31:17.453] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:2:0)
[20:31:17.545] Build status: SCHEDULED
[20:31:17.545] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:4:0) after 8m29s
[20:39:46.573] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:4:0)
[20:39:46.590] Build status: SCHEDULED
[20:39:46.590] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:5:0) after 7m2s
[20:46:48.628] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:5:0)
[20:46:48.658] Build status: SCHEDULED
[20:46:48.658] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:6:0) after 7m54s
[20:54:42.689] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:6:0)
[20:54:42.710] Build status: SCHEDULED
[20:54:42.710] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:7:0) after 4m53s
[20:59:35.705] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:7:0)
[20:59:35.724] Build status: SCHEDULED
[20:59:35.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:8:0) after 7m28s
[21:05:11.757] Received PubSub notification, asking Buildbucket for the build status
[21:05:11.791] Build status: STARTED
[21:07:03.750] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:8:0)
[21:07:03.773] Build status: STARTED
[21:07:03.773] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:10:0) after 9m23s
[21:16:26.787] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:10:0)
[21:16:26.811] Build status: STARTED
[21:16:26.811] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:11:0) after 1m29s
[21:17:55.833] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:11:0)
[21:17:55.870] Build status: STARTED
[21:17:55.870] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:12:0) after 5m54s
[21:23:49.890] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:12:0)
[21:23:49.914] Build status: STARTED
[21:23:49.914] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:13:0) after 8m3s
[21:31:52.944] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:13:0)
[21:31:52.974] Build status: STARTED
[21:31:52.974] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:14:0) after 9m13s
[21:41:05.974] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:14:0)
[21:41:05.994] Build status: STARTED
[21:41:05.994] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:15:0) after 2m33s
[21:43:39.028] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:15:0)
[21:43:39.045] Build status: STARTED
[21:43:39.045] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:16:0) after 3m18s
[21:46:57.066] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:16:0)
[21:46:57.096] Build status: STARTED
[21:46:57.096] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:17:0) after 3m5s
[21:50:02.146] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:17:0)
[21:50:02.164] Build status: STARTED
[21:50:02.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:18:0) after 3m55s
[21:53:57.466] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:18:0)
[21:53:57.485] Build status: STARTED
[21:53:57.485] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:19:0) after 5m24s
[21:59:21.514] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:19:0)
[21:59:21.535] Build status: STARTED
[21:59:21.535] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:20:0) after 7m32s
[22:06:53.644] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:20:0)
[22:06:53.728] Build status: STARTED
[22:06:53.728] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:21:0) after 7m58s
[22:14:51.804] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:21:0)
[22:14:51.829] Build status: STARTED
[22:14:51.829] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:22:0) after 5m21s
[22:20:12.887] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:22:0)
[22:20:12.911] Build status: STARTED
[22:20:12.911] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:23:0) after 4m58s
[22:25:10.937] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:23:0)
[22:25:10.959] Build status: STARTED
[22:25:10.959] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:24:0) after 1m22s
[22:26:33.307] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:24:0)
[22:26:33.337] Build status: STARTED
[22:26:33.337] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:25:0) after 7m13s
[22:33:46.362] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:25:0)
[22:33:46.414] Build status: STARTED
[22:33:46.414] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:26:0) after 2m23s
[22:36:09.435] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:26:0)
[22:36:09.456] Build status: STARTED
[22:36:09.456] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:27:0) after 1m44s
[22:37:53.464] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:27:0)
[22:37:53.489] Build status: STARTED
[22:37:53.489] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:28:0) after 7m35s
[22:45:28.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:28:0)
[22:45:28.561] Build status: STARTED
[22:45:28.561] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:29:0) after 1m7s
[22:46:35.621] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:29:0)
[22:46:35.666] Build status: STARTED
[22:46:35.666] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Fuchsia (dbg):8918345354732667728:30:0) after 7m42s
[22:50:02.134] Received PubSub notification, asking Buildbucket for the build status
[22:50:02.216] Build:
{
"id": "8752915292785208193",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Fuchsia (dbg)"
},
"number": 19650,
"createdBy": "project:chromium",
"createTime": "2024-03-20T20:28:33.799375799Z",
"startTime": "2024-03-20T21:05:10.715309Z",
"endTime": "2024-03-20T22:50:01.572370Z",
"updateTime": "2024-03-20T22:50:01.572370Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "a3ae77554afcde1a06823c7ccf70f4a6a1b49b0b",
"ref": "refs/heads/main"
}
}
}
[22:50:02.216] Invocation finished in 2h21m29.6580017s with status SUCCEEDED