[23:42:52.170] New invocation is queued and will start shortly
[23:42:53.254] Starting the invocation (attempt 1)
[23:42:53.284] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/b3f088ca5013a5ccd434b992ac6ff4cb18078761"
[23:42:53.284] Popped gitiles commit info from properties and tags
[23:42:53.284] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[23:42:53.284] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[23:42:53.284] Buildbucket request:
{
"requestId": "9010651437522042032",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b3f088ca5013a5ccd434b992ac6ff4cb18078761",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9010651437522042032"
},
{
"key": "scheduler_job_id",
"value": "chromium/Deterministic Android (dbg)"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[23:42:53.832] Scheduled build:
{
"id": "8845221375612411776",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 9245,
"createdBy": "project:chromium",
"createTime": "2021-06-05T23:42:53.358986Z",
"updateTime": "2021-06-05T23:42:53.759410Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b3f088ca5013a5ccd434b992ac6ff4cb18078761",
"ref": "refs/heads/master"
}
}
}
[23:42:53.832] Task URL: https://cr-buildbucket.appspot.com/build/8845221375612411776
[23:42:53.832] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:2:0) after 3m40s
[23:42:57.246] Received PubSub notification, asking Buildbucket for the build status
[23:42:57.414] Build status: STARTED
[23:46:34.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:2:0)
[23:46:34.176] Build status: STARTED
[23:46:34.176] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:4:0) after 7m45s
[23:54:19.153] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:4:0)
[23:54:19.180] Build status: STARTED
[23:54:19.180] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:5:0) after 7m25s
[00:01:44.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:5:0)
[00:01:44.231] Build status: STARTED
[00:01:44.231] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:6:0) after 5m28s
[00:07:12.348] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:6:0)
[00:07:12.383] Build status: STARTED
[00:07:12.383] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:7:0) after 5m3s
[00:12:15.994] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:7:0)
[00:12:16.022] Build status: STARTED
[00:12:16.022] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:8:0) after 6m30s
[00:18:46.221] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:8:0)
[00:18:46.248] Build status: STARTED
[00:18:46.248] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:9:0) after 9m46s
[00:28:32.542] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:9:0)
[00:28:32.588] Build status: STARTED
[00:28:32.588] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:10:0) after 8m4s
[00:36:36.607] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:10:0)
[00:36:36.639] Build status: STARTED
[00:36:36.639] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:11:0) after 4m45s
[00:41:21.768] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:11:0)
[00:41:21.793] Build status: STARTED
[00:41:21.793] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:12:0) after 2m16s
[00:43:38.690] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:12:0)
[00:43:38.722] Build status: STARTED
[00:43:38.722] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:13:0) after 9m17s
[00:52:56.178] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:13:0)
[00:52:56.216] Build status: STARTED
[00:52:56.216] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:14:0) after 9m5s
[01:02:01.827] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:14:0)
[01:02:01.874] Build status: STARTED
[01:02:01.874] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:15:0) after 8m24s
[01:10:25.909] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:15:0)
[01:10:25.935] Build status: STARTED
[01:10:25.935] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:16:0) after 1m34s
[01:12:00.098] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:16:0)
[01:12:00.127] Build status: STARTED
[01:12:00.127] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:17:0) after 7m10s
[01:19:10.665] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:17:0)
[01:19:10.696] Build status: STARTED
[01:19:10.696] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:18:0) after 8m29s
[01:27:40.064] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:18:0)
[01:27:40.091] Build status: STARTED
[01:27:40.091] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:19:0) after 5m36s
[01:33:16.316] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:19:0)
[01:33:16.359] Build status: STARTED
[01:33:16.359] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:20:0) after 2m43s
[01:35:59.503] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:20:0)
[01:35:59.534] Build status: STARTED
[01:35:59.534] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:21:0) after 6m12s
[01:42:11.752] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:21:0)
[01:42:11.779] Build status: STARTED
[01:42:11.779] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:22:0) after 4m53s
[01:47:04.807] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:22:0)
[01:47:04.835] Build status: STARTED
[01:47:04.835] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:23:0) after 5m37s
[01:52:41.930] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:23:0)
[01:52:41.954] Build status: STARTED
[01:52:41.954] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:24:0) after 8m40s
[02:01:22.124] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:24:0)
[02:01:22.165] Build status: STARTED
[02:01:22.165] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:25:0) after 5m20s
[02:06:42.878] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:25:0)
[02:06:42.917] Build status: STARTED
[02:06:42.917] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:26:0) after 2m41s
[02:09:24.810] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:26:0)
[02:09:24.838] Build status: STARTED
[02:09:24.838] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:27:0) after 7m0s
[02:16:26.101] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:27:0)
[02:16:26.139] Build status: STARTED
[02:16:26.139] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:28:0) after 7m14s
[02:23:40.486] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:28:0)
[02:23:40.518] Build status: STARTED
[02:23:40.518] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:29:0) after 7m0s
[02:30:40.568] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:29:0)
[02:30:40.592] Build status: STARTED
[02:30:40.592] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:30:0) after 7m40s
[02:38:20.818] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:30:0)
[02:38:20.846] Build status: STARTED
[02:38:20.846] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:31:0) after 2m4s
[02:40:24.884] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:31:0)
[02:40:24.912] Build status: STARTED
[02:40:24.912] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:32:0) after 7m35s
[02:47:59.931] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:32:0)
[02:48:00.029] Build status: STARTED
[02:48:00.029] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:33:0) after 5m56s
[02:53:56.294] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:33:0)
[02:53:56.331] Build status: STARTED
[02:53:56.331] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:34:0) after 5m12s
[02:59:08.552] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:34:0)
[02:59:08.579] Build status: STARTED
[02:59:08.579] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:35:0) after 3m40s
[03:02:48.700] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:35:0)
[03:02:48.727] Build status: STARTED
[03:02:48.727] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:36:0) after 2m11s
[03:05:00.127] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:36:0)
[03:05:00.155] Build status: STARTED
[03:05:00.155] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:37:0) after 2m10s
[03:07:10.175] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:37:0)
[03:07:10.201] Build status: STARTED
[03:07:10.201] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:38:0) after 7m4s
[03:14:14.235] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:38:0)
[03:14:14.264] Build status: STARTED
[03:14:14.264] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:39:0) after 7m27s
[03:21:41.367] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:39:0)
[03:21:41.394] Build status: STARTED
[03:21:41.394] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:40:0) after 5m8s
[03:26:49.524] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:40:0)
[03:26:49.551] Build status: STARTED
[03:26:49.551] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:41:0) after 6m2s
[03:32:52.070] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:41:0)
[03:32:52.096] Build status: STARTED
[03:32:52.096] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:42:0) after 1m39s
[03:34:31.117] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:42:0)
[03:34:31.152] Build status: STARTED
[03:34:31.153] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:43:0) after 2m52s
[03:37:23.200] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:43:0)
[03:37:23.226] Build status: STARTED
[03:37:23.226] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:44:0) after 8m31s
[03:45:54.539] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:44:0)
[03:45:54.569] Build status: STARTED
[03:45:54.570] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:45:0) after 4m42s
[03:50:36.805] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:45:0)
[03:50:36.830] Build status: STARTED
[03:50:36.830] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:46:0) after 1m54s
[03:52:31.086] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:46:0)
[03:52:31.124] Build status: STARTED
[03:52:31.124] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:47:0) after 1m6s
[03:53:37.139] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:47:0)
[03:53:37.167] Build status: STARTED
[03:53:37.167] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:48:0) after 5m55s
[03:59:32.651] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:48:0)
[03:59:32.678] Build status: STARTED
[03:59:32.678] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:49:0) after 2m42s
[04:02:14.708] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:49:0)
[04:02:14.749] Build status: STARTED
[04:02:14.749] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:50:0) after 9m27s
[04:11:42.027] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:50:0)
[04:11:42.053] Build status: STARTED
[04:11:42.053] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:51:0) after 5m15s
[04:16:57.210] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:51:0)
[04:16:57.245] Build status: STARTED
[04:16:57.245] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:52:0) after 3m9s
[04:20:06.264] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:52:0)
[04:20:06.320] Build status: STARTED
[04:20:06.320] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:53:0) after 1m20s
[04:21:26.317] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:53:0)
[04:21:26.343] Build status: STARTED
[04:21:26.343] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:54:0) after 3m14s
[04:24:40.362] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:54:0)
[04:24:40.423] Build status: STARTED
[04:24:40.423] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:55:0) after 8m16s
[04:32:56.469] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:55:0)
[04:32:56.508] Build status: STARTED
[04:32:56.508] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:56:0) after 6m7s
[04:39:03.529] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:56:0)
[04:39:03.559] Build status: STARTED
[04:39:03.559] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:57:0) after 8m19s
[04:47:22.580] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:57:0)
[04:47:22.614] Build status: STARTED
[04:47:22.614] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:58:0) after 2m29s
[04:49:51.636] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:58:0)
[04:49:51.664] Build status: STARTED
[04:49:51.664] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:59:0) after 3m49s
[04:53:41.084] Handling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:59:0)
[04:53:41.112] Build status: STARTED
[04:53:41.112] Scheduling timer "check-buildbucket-build-status" (chromium/Deterministic Android (dbg):9010651437522042032:60:0) after 4m8s
[04:56:51.554] Received PubSub notification, asking Buildbucket for the build status
[04:56:51.580] Build:
{
"id": "8845221375612411776",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "Deterministic Android (dbg)"
},
"number": 9245,
"createdBy": "project:chromium",
"createTime": "2021-06-05T23:42:53.358986Z",
"startTime": "2021-06-05T23:42:56.066788Z",
"endTime": "2021-06-06T04:56:51.237083056Z",
"updateTime": "2021-06-06T04:56:51.237504432Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "b3f088ca5013a5ccd434b992ac6ff4cb18078761",
"ref": "refs/heads/master"
}
}
}
[04:56:51.580] Invocation finished in 5h13m59.425791378s with status SUCCEEDED