[05:47:54.365] New invocation is queued and will start shortly
[05:47:55.587] Starting the invocation (attempt 1)
[05:47:55.623] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/299773e0cd921e08427d1368b37f010ea01ce15a"
[05:47:55.623] Popped gitiles commit info from properties and tags
[05:47:55.623] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[05:47:55.623] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[05:47:55.623] Buildbucket request:
{
"requestId": "9006008026120499920",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "299773e0cd921e08427d1368b37f010ea01ce15a",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9006008026120499920"
},
{
"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"
}
}
[05:47:56.128] Scheduled build:
{
"id": "8840577964060276720",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 22347,
"createdBy": "project:chromium",
"createTime": "2021-07-27T05:47:55.696360Z",
"updateTime": "2021-07-27T05:47:56.057636Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "299773e0cd921e08427d1368b37f010ea01ce15a",
"ref": "refs/heads/main"
}
}
}
[05:47:56.128] Task URL: https://cr-buildbucket.appspot.com/build/8840577964060276720
[05:47:56.128] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:2:0) after 7m57s
[05:55:53.154] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:2:0)
[05:55:53.181] Build status: SCHEDULED
[05:55:53.181] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:3:0) after 2m19s
[05:58:12.309] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:3:0)
[05:58:12.338] Build status: SCHEDULED
[05:58:12.338] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:4:0) after 8m40s
[06:06:52.466] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:4:0)
[06:06:52.496] Build status: SCHEDULED
[06:06:52.496] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:5:0) after 5m40s
[06:12:32.601] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:5:0)
[06:12:32.630] Build status: SCHEDULED
[06:12:32.630] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:6:0) after 2m1s
[06:14:33.653] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:6:0)
[06:14:33.682] Build status: SCHEDULED
[06:14:33.682] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:7:0) after 8m21s
[06:22:54.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:7:0)
[06:22:54.750] Build status: SCHEDULED
[06:22:54.750] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:8:0) after 1m2s
[06:23:56.734] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:8:0)
[06:23:56.765] Build status: SCHEDULED
[06:23:56.765] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:9:0) after 4m46s
[06:28:42.799] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:9:0)
[06:28:42.828] Build status: SCHEDULED
[06:28:42.828] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:10:0) after 6m9s
[06:34:51.994] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:10:0)
[06:34:52.025] Build status: SCHEDULED
[06:34:52.025] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:11:0) after 8m17s
[06:43:09.184] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:11:0)
[06:43:09.276] Build status: SCHEDULED
[06:43:09.276] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:12:0) after 4m15s
[06:47:24.317] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:12:0)
[06:47:24.391] Build status: SCHEDULED
[06:47:24.391] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:13:0) after 8m41s
[06:56:05.421] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:13:0)
[06:56:05.471] Build status: SCHEDULED
[06:56:05.471] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:14:0) after 6m56s
[07:03:01.498] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:14:0)
[07:03:01.552] Build status: SCHEDULED
[07:03:01.552] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:15:0) after 2m35s
[07:05:36.856] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:15:0)
[07:05:36.885] Build status: SCHEDULED
[07:05:36.885] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:16:0) after 5m7s
[07:10:44.081] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:16:0)
[07:10:44.127] Build status: SCHEDULED
[07:10:44.127] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:17:0) after 4m9s
[07:14:53.278] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:17:0)
[07:14:53.338] Build status: SCHEDULED
[07:14:53.338] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:18:0) after 2m40s
[07:17:33.374] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:18:0)
[07:17:33.416] Build status: SCHEDULED
[07:17:33.416] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:19:0) after 9m31s
[07:21:13.127] Received PubSub notification, asking Buildbucket for the build status
[07:21:13.231] Build status: STARTED
[07:27:04.527] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:19:0)
[07:27:04.587] Build status: STARTED
[07:27:04.587] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:21:0) after 5m56s
[07:33:00.849] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:21:0)
[07:33:00.925] Build status: STARTED
[07:33:00.925] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:22:0) after 3m32s
[07:36:32.963] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:22:0)
[07:36:32.988] Build status: STARTED
[07:36:32.988] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:23:0) after 9m4s
[07:45:37.021] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:23:0)
[07:45:37.076] Build status: STARTED
[07:45:37.076] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:24:0) after 5m42s
[07:51:19.308] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:24:0)
[07:51:19.341] Build status: STARTED
[07:51:19.341] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:25:0) after 2m33s
[07:53:52.423] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:25:0)
[07:53:52.461] Build status: STARTED
[07:53:52.461] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:26:0) after 4m56s
[07:58:48.489] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:26:0)
[07:58:48.518] Build status: STARTED
[07:58:48.518] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:27:0) after 8m16s
[08:07:04.738] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:27:0)
[08:07:04.765] Build status: STARTED
[08:07:04.765] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:28:0) after 9m51s
[08:16:55.787] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:28:0)
[08:16:55.813] Build status: STARTED
[08:16:55.813] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:29:0) after 6m38s
[08:23:33.940] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:29:0)
[08:23:33.968] Build status: STARTED
[08:23:33.968] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:30:0) after 2m54s
[08:26:28.094] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:30:0)
[08:26:28.131] Build status: STARTED
[08:26:28.131] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:31:0) after 5m38s
[08:32:06.549] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:31:0)
[08:32:06.581] Build status: STARTED
[08:32:06.581] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:32:0) after 5m41s
[08:37:47.694] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:32:0)
[08:37:47.781] Build status: STARTED
[08:37:47.781] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:33:0) after 5m38s
[08:43:25.805] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:33:0)
[08:43:25.832] Build status: STARTED
[08:43:25.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:34:0) after 6m25s
[08:49:50.854] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:34:0)
[08:49:50.886] Build status: STARTED
[08:49:50.886] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:35:0) after 8m28s
[08:58:19.024] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:35:0)
[08:58:19.051] Build status: STARTED
[08:58:19.051] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:36:0) after 9m36s
[09:07:55.079] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:36:0)
[09:07:55.108] Build status: STARTED
[09:07:55.108] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:37:0) after 3m39s
[09:11:34.112] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:37:0)
[09:11:34.139] Build status: STARTED
[09:11:34.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:38:0) after 5m37s
[09:17:11.398] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:38:0)
[09:17:11.428] Build status: STARTED
[09:17:11.428] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:39:0) after 7m0s
[09:24:11.460] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:39:0)
[09:24:11.490] Build status: STARTED
[09:24:11.490] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:40:0) after 3m41s
[09:27:52.667] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:40:0)
[09:27:52.694] Build status: STARTED
[09:27:52.695] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:41:0) after 2m3s
[09:29:55.718] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:41:0)
[09:29:55.748] Build status: STARTED
[09:29:55.748] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:42:0) after 5m16s
[09:35:11.811] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:42:0)
[09:35:11.837] Build status: STARTED
[09:35:11.837] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:43:0) after 9m44s
[09:44:55.937] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:43:0)
[09:44:55.964] Build status: STARTED
[09:44:55.964] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:44:0) after 7m24s
[09:52:19.989] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:44:0)
[09:52:20.016] Build status: STARTED
[09:52:20.016] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:45:0) after 4m43s
[09:57:03.161] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:45:0)
[09:57:03.191] Build status: STARTED
[09:57:03.191] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:46:0) after 4m10s
[10:01:13.205] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:46:0)
[10:01:13.237] Build status: STARTED
[10:01:13.237] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:47:0) after 7m35s
[10:08:48.263] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:47:0)
[10:08:48.294] Build status: STARTED
[10:08:48.294] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:48:0) after 7m30s
[10:16:18.322] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:48:0)
[10:16:18.358] Build status: STARTED
[10:16:18.358] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:49:0) after 2m44s
[10:19:02.384] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:49:0)
[10:19:02.414] Build status: STARTED
[10:19:02.414] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:50:0) after 3m7s
[10:22:09.442] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:50:0)
[10:22:09.476] Build status: STARTED
[10:22:09.476] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:51:0) after 3m4s
[10:25:13.611] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:51:0)
[10:25:13.640] Build status: STARTED
[10:25:13.640] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:52:0) after 5m1s
[10:30:14.784] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:52:0)
[10:30:14.836] Build status: STARTED
[10:30:14.836] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:53:0) after 8m18s
[10:38:32.858] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:53:0)
[10:38:32.883] Build status: STARTED
[10:38:32.883] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:54:0) after 3m37s
[10:42:10.014] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:54:0)
[10:42:10.041] Build status: STARTED
[10:42:10.041] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:55:0) after 6m52s
[10:49:02.132] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:55:0)
[10:49:02.229] Build status: STARTED
[10:49:02.229] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:56:0) after 3m38s
[10:52:40.257] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:56:0)
[10:52:40.282] Build status: STARTED
[10:52:40.282] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:57:0) after 9m3s
[11:01:43.311] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:57:0)
[11:01:43.345] Build status: STARTED
[11:01:43.345] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Linux (dbg):9006008026120499920:58:0) after 8m24s
[11:05:52.844] Received PubSub notification, asking Buildbucket for the build status
[11:05:52.869] Build:
{
"id": "8840577964060276720",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Linux (dbg)"
},
"number": 22347,
"createdBy": "project:chromium",
"createTime": "2021-07-27T05:47:55.696360Z",
"startTime": "2021-07-27T07:21:11.017536Z",
"endTime": "2021-07-27T11:05:52.473799321Z",
"updateTime": "2021-07-27T11:05:52.474251404Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "299773e0cd921e08427d1368b37f010ea01ce15a",
"ref": "refs/heads/main"
}
}
}
[11:05:52.869] Invocation finished in 5h17m58.521222996s with status SUCCEEDED