[04:18:00.626] New invocation is queued and will start shortly
[04:18:01.695] Starting the invocation (attempt 1)
[04:18:01.794] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/45badbc16352b34089bdcba3fd7a225a8e4d9069"
[04:18:01.794] Popped gitiles commit info from properties and tags
[04:18:01.794] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[04:18:01.795] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[04:18:01.795] Buildbucket request:
{
"requestId": "9013805020979445984",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45badbc16352b34089bdcba3fd7a225a8e4d9069",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9013805020979445984"
},
{
"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"
}
}
[04:18:03.067] Scheduled build:
{
"id": "8848374959004523424",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 21744,
"createdBy": "project:chromium",
"createTime": "2021-05-02T04:18:01.873366Z",
"updateTime": "2021-05-02T04:18:02.703569Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45badbc16352b34089bdcba3fd7a225a8e4d9069",
"ref": "refs/heads/master"
}
}
}
[04:18:03.067] Task URL: https://cr-buildbucket.appspot.com/build/8848374959004523424
[04:18:03.067] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:2:0) after 4m46s
[04:22:49.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:2:0)
[04:22:49.153] Build status: SCHEDULED
[04:22:49.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:3:0) after 5m56s
[04:23:09.099] Received PubSub notification, asking Buildbucket for the build status
[04:23:09.127] Build status: STARTED
[04:28:45.168] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:3:0)
[04:28:45.200] Build status: STARTED
[04:28:45.200] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:5:0) after 9m33s
[04:38:18.406] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:5:0)
[04:38:18.457] Build status: STARTED
[04:38:18.457] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:6:0) after 9m2s
[04:47:20.472] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:6:0)
[04:47:20.499] Build status: STARTED
[04:47:20.499] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:7:0) after 4m6s
[04:51:26.517] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:7:0)
[04:51:26.543] Build status: STARTED
[04:51:26.544] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:8:0) after 4m6s
[04:55:32.837] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:8:0)
[04:55:32.886] Build status: STARTED
[04:55:32.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:9:0) after 6m45s
[05:02:18.351] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:9:0)
[05:02:18.375] Build status: STARTED
[05:02:18.375] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:10:0) after 5m20s
[05:07:38.645] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:10:0)
[05:07:38.670] Build status: STARTED
[05:07:38.670] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:11:0) after 9m44s
[05:17:22.684] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:11:0)
[05:17:22.709] Build status: STARTED
[05:17:22.709] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:12:0) after 5m8s
[05:22:30.891] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:12:0)
[05:22:30.927] Build status: STARTED
[05:22:30.927] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:13:0) after 9m19s
[05:31:50.623] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:13:0)
[05:31:50.760] Build status: STARTED
[05:31:50.760] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:14:0) after 3m46s
[05:35:36.862] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:14:0)
[05:35:36.886] Build status: STARTED
[05:35:36.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:15:0) after 8m3s
[05:43:40.103] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:15:0)
[05:43:40.126] Build status: STARTED
[05:43:40.126] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:16:0) after 2m58s
[05:46:38.143] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:16:0)
[05:46:38.168] Build status: STARTED
[05:46:38.168] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:17:0) after 5m10s
[05:51:48.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:17:0)
[05:51:48.209] Build status: STARTED
[05:51:48.209] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:18:0) after 2m7s
[05:53:55.231] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:18:0)
[05:53:55.267] Build status: STARTED
[05:53:55.267] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:19:0) after 2m18s
[05:56:13.717] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:19:0)
[05:56:13.744] Build status: STARTED
[05:56:13.744] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:20:0) after 6m35s
[06:02:49.249] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:20:0)
[06:02:49.278] Build status: STARTED
[06:02:49.278] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:21:0) after 5m58s
[06:08:47.298] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:21:0)
[06:08:47.326] Build status: STARTED
[06:08:47.326] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:22:0) after 1m35s
[06:10:22.405] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:22:0)
[06:10:22.435] Build status: STARTED
[06:10:22.435] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:23:0) after 5m19s
[06:15:41.800] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:23:0)
[06:15:41.826] Build status: STARTED
[06:15:41.826] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:24:0) after 5m34s
[06:21:15.842] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:24:0)
[06:21:15.868] Build status: STARTED
[06:21:15.868] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:25:0) after 5m41s
[06:26:57.032] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:25:0)
[06:26:57.058] Build status: STARTED
[06:26:57.058] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:26:0) after 6m18s
[06:33:15.091] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:26:0)
[06:33:15.116] Build status: STARTED
[06:33:15.116] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:27:0) after 1m11s
[06:34:26.426] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:27:0)
[06:34:26.451] Build status: STARTED
[06:34:26.451] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:28:0) after 6m59s
[06:41:25.771] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:28:0)
[06:41:25.795] Build status: STARTED
[06:41:25.795] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:29:0) after 8m1s
[06:49:26.977] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:29:0)
[06:49:27.011] Build status: STARTED
[06:49:27.011] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:30:0) after 6m27s
[06:55:54.377] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:30:0)
[06:55:54.404] Build status: STARTED
[06:55:54.404] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:31:0) after 8m40s
[07:04:34.420] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:31:0)
[07:04:34.445] Build status: STARTED
[07:04:34.445] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:32:0) after 3m49s
[07:08:23.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:32:0)
[07:08:23.534] Build status: STARTED
[07:08:23.534] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:33:0) after 6m12s
[07:14:35.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:33:0)
[07:14:35.577] Build status: STARTED
[07:14:35.577] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:34:0) after 6m14s
[07:20:49.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:34:0)
[07:20:49.991] Build status: STARTED
[07:20:49.991] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:35:0) after 7m6s
[07:27:56.006] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:35:0)
[07:27:56.036] Build status: STARTED
[07:27:56.036] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:36:0) after 4m45s
[07:32:41.949] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:36:0)
[07:32:41.978] Build status: STARTED
[07:32:41.978] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:37:0) after 6m39s
[07:39:21.128] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:37:0)
[07:39:21.176] Build status: STARTED
[07:39:21.176] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:38:0) after 8m34s
[07:47:55.497] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:38:0)
[07:47:55.525] Build status: STARTED
[07:47:55.525] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:39:0) after 2m57s
[07:50:52.633] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:39:0)
[07:50:52.659] Build status: STARTED
[07:50:52.659] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:40:0) after 3m46s
[07:54:38.682] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:40:0)
[07:54:38.711] Build status: STARTED
[07:54:38.711] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:41:0) after 7m55s
[08:02:33.790] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:41:0)
[08:02:33.828] Build status: STARTED
[08:02:33.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:42:0) after 8m20s
[08:10:54.182] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:42:0)
[08:10:54.212] Build status: STARTED
[08:10:54.212] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9013805020979445984:43:0) after 5m55s
[08:15:52.374] Received PubSub notification, asking Buildbucket for the build status
[08:15:52.397] Build:
{
"id": "8848374959004523424",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 21744,
"createdBy": "project:chromium",
"createTime": "2021-05-02T04:18:01.873366Z",
"startTime": "2021-05-02T04:23:08.277522Z",
"endTime": "2021-05-02T08:15:51.747943Z",
"updateTime": "2021-05-02T08:15:52.018625Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "45badbc16352b34089bdcba3fd7a225a8e4d9069",
"ref": "refs/heads/master"
}
}
}
[08:15:52.397] Invocation finished in 3h57m51.791507999s with status SUCCEEDED