[19:41:44.662] New invocation is queued and will start shortly
[19:41:45.833] Starting the invocation (attempt 1)
[19:41:45.888] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/1d886d1a20c66afa725e340845aa42ba74a9be0d"
[19:41:45.888] Popped gitiles commit info from properties and tags
[19:41:45.888] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[19:41:45.888] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[19:41:45.888] Buildbucket request:
{
"requestId": "8990644678228847728",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d886d1a20c66afa725e340845aa42ba74a9be0d",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8990644678228847728"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Linux (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[19:41:46.461] Scheduled build:
{
"id": "8825214616196517377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 24042,
"createdBy": "project:chromium",
"createTime": "2022-01-12T19:41:45.968419569Z",
"updateTime": "2022-01-12T19:41:45.968419569Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d886d1a20c66afa725e340845aa42ba74a9be0d",
"ref": "refs/heads/main"
}
}
}
[19:41:46.461] Task URL: https://cr-buildbucket.appspot.com/build/8825214616196517377
[19:41:46.461] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:2:0) after 6m17s
[19:42:06.039] Received PubSub notification, asking Buildbucket for the build status
[19:42:06.062] Build status: STARTED
[19:48:03.621] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:2:0)
[19:48:03.670] Build status: STARTED
[19:48:03.670] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:4:0) after 8m57s
[19:57:00.823] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:4:0)
[19:57:00.845] Build status: STARTED
[19:57:00.845] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:5:0) after 8m23s
[20:05:24.077] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:5:0)
[20:05:24.170] Build status: STARTED
[20:05:24.170] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:6:0) after 3m1s
[20:08:25.359] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:6:0)
[20:08:25.407] Build status: STARTED
[20:08:25.407] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:7:0) after 7m7s
[20:15:32.478] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:7:0)
[20:15:32.555] Build status: STARTED
[20:15:32.555] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:8:0) after 9m57s
[20:25:29.598] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:8:0)
[20:25:29.623] Build status: STARTED
[20:25:29.623] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:9:0) after 3m43s
[20:29:12.758] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:9:0)
[20:29:12.797] Build status: STARTED
[20:29:12.797] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:10:0) after 7m53s
[20:37:06.015] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:10:0)
[20:37:06.036] Build status: STARTED
[20:37:06.036] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:11:0) after 1m52s
[20:38:58.058] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:11:0)
[20:38:58.088] Build status: STARTED
[20:38:58.088] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:12:0) after 7m23s
[20:46:21.149] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:12:0)
[20:46:21.188] Build status: STARTED
[20:46:21.188] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:13:0) after 5m48s
[20:52:09.323] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:13:0)
[20:52:09.363] Build status: STARTED
[20:52:09.363] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:14:0) after 3m9s
[20:55:18.526] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:14:0)
[20:55:18.552] Build status: STARTED
[20:55:18.552] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:15:0) after 6m47s
[21:02:05.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:15:0)
[21:02:05.747] Build status: STARTED
[21:02:05.747] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:16:0) after 5m52s
[21:07:57.957] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:16:0)
[21:07:57.977] Build status: STARTED
[21:07:57.977] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:17:0) after 1m46s
[21:09:44.026] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:17:0)
[21:09:44.048] Build status: STARTED
[21:09:44.048] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:18:0) after 2m49s
[21:12:33.127] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:18:0)
[21:12:33.159] Build status: STARTED
[21:12:33.159] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:19:0) after 8m18s
[21:20:51.510] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:19:0)
[21:20:51.534] Build status: STARTED
[21:20:51.534] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:20:0) after 6m7s
[21:26:58.702] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:20:0)
[21:26:58.724] Build status: STARTED
[21:26:58.724] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:21:0) after 6m55s
[21:33:53.753] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:21:0)
[21:33:53.786] Build status: STARTED
[21:33:53.786] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:22:0) after 9m1s
[21:42:54.818] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:22:0)
[21:42:54.847] Build status: STARTED
[21:42:54.847] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:23:0) after 6m24s
[21:49:18.886] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:23:0)
[21:49:19.605] Build status: STARTED
[21:49:19.605] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:24:0) after 8m39s
[21:57:58.728] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:24:0)
[21:57:58.761] Build status: STARTED
[21:57:58.761] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:25:0) after 8m31s
[22:06:29.839] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:25:0)
[22:06:29.875] Build status: STARTED
[22:06:29.875] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:26:0) after 4m11s
[22:10:41.082] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:26:0)
[22:10:41.099] Build status: STARTED
[22:10:41.099] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:27:0) after 2m58s
[22:13:39.233] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:27:0)
[22:13:39.266] Build status: STARTED
[22:13:39.266] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:28:0) after 3m22s
[22:17:01.286] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:28:0)
[22:17:01.310] Build status: STARTED
[22:17:01.310] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:29:0) after 7m54s
[22:24:55.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:29:0)
[22:24:55.490] Build status: STARTED
[22:24:55.490] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:30:0) after 8m18s
[22:33:13.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:30:0)
[22:33:13.696] Build status: STARTED
[22:33:13.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:31:0) after 5m39s
[22:38:52.732] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:31:0)
[22:38:52.758] Build status: STARTED
[22:38:52.758] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:32:0) after 4m4s
[22:42:56.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:32:0)
[22:42:57.150] Build status: STARTED
[22:42:57.150] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:33:0) after 8m20s
[22:51:17.274] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:33:0)
[22:51:17.308] Build status: STARTED
[22:51:17.308] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:34:0) after 2m31s
[22:53:48.414] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:34:0)
[22:53:48.433] Build status: STARTED
[22:53:48.433] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):8990644678228847728:35:0) after 1m17s
[22:55:04.130] Received PubSub notification, asking Buildbucket for the build status
[22:55:04.230] Build:
{
"id": "8825214616196517377",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 24042,
"createdBy": "project:chromium",
"createTime": "2022-01-12T19:41:45.968419569Z",
"startTime": "2022-01-12T19:42:04.651278Z",
"endTime": "2022-01-12T22:55:03.780433480Z",
"updateTime": "2022-01-12T22:55:03.780433480Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "1d886d1a20c66afa725e340845aa42ba74a9be0d",
"ref": "refs/heads/main"
}
}
}
[22:55:04.230] Invocation finished in 3h13m19.584846042s with status SUCCEEDED