[22:56:30.156] New invocation is queued and will start shortly
[22:56:31.372] Starting the invocation (attempt 1)
[22:56:31.410] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/02412c76b611678b2d450bbac32f42f50ec2bb7b"
[22:56:31.410] Popped gitiles commit info from properties and tags
[22:56:31.410] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[22:56:31.410] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[22:56:31.410] Buildbucket request:
{
"requestId": "9006577491186778960",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02412c76b611678b2d450bbac32f42f50ec2bb7b",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "9006577491186778960"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroid x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[22:56:32.012] Scheduled build:
{
"id": "8841147429106885760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 1481,
"createdBy": "project:chromium",
"createTime": "2021-07-20T22:56:31.507796Z",
"updateTime": "2021-07-20T22:56:31.929465Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02412c76b611678b2d450bbac32f42f50ec2bb7b",
"ref": "refs/heads/main"
}
}
}
[22:56:32.012] Task URL: https://cr-buildbucket.appspot.com/build/8841147429106885760
[22:56:32.012] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:2:0) after 5m9s
[22:56:47.009] Received PubSub notification, asking Buildbucket for the build status
[22:56:47.035] Build status: STARTED
[23:01:41.152] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:2:0)
[23:01:41.214] Build status: STARTED
[23:01:41.214] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:4:0) after 1m3s
[23:02:44.299] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:4:0)
[23:02:44.335] Build status: STARTED
[23:02:44.335] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:5:0) after 3m11s
[23:05:55.388] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:5:0)
[23:05:55.472] Build status: STARTED
[23:05:55.472] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:6:0) after 3m11s
[23:09:07.104] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:6:0)
[23:09:07.141] Build status: STARTED
[23:09:07.141] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:7:0) after 7m29s
[23:16:36.153] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:7:0)
[23:16:36.180] Build status: STARTED
[23:16:36.180] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:8:0) after 4m50s
[23:21:26.330] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:8:0)
[23:21:26.393] Build status: STARTED
[23:21:26.393] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:9:0) after 6m19s
[23:27:45.456] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:9:0)
[23:27:45.488] Build status: STARTED
[23:27:45.488] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:10:0) after 2m4s
[23:29:49.524] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:10:0)
[23:29:49.559] Build status: STARTED
[23:29:49.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:11:0) after 8m54s
[23:38:43.614] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:11:0)
[23:38:43.640] Build status: STARTED
[23:38:43.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:12:0) after 7m15s
[23:45:59.122] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:12:0)
[23:45:59.164] Build status: STARTED
[23:45:59.164] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:13:0) after 6m19s
[23:52:18.193] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:13:0)
[23:52:18.228] Build status: STARTED
[23:52:18.228] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:14:0) after 9m55s
[00:02:13.484] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:14:0)
[00:02:13.518] Build status: STARTED
[00:02:13.518] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:15:0) after 7m14s
[00:09:27.593] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:15:0)
[00:09:27.624] Build status: STARTED
[00:09:27.624] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:16:0) after 7m6s
[00:16:33.722] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:16:0)
[00:16:33.789] Build status: STARTED
[00:16:33.789] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:17:0) after 8m17s
[00:24:50.972] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:17:0)
[00:24:51.000] Build status: STARTED
[00:24:51.000] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:18:0) after 6m51s
[00:31:42.181] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:18:0)
[00:31:42.219] Build status: STARTED
[00:31:42.219] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:19:0) after 8m5s
[00:39:47.289] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:19:0)
[00:39:47.323] Build status: STARTED
[00:39:47.323] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:20:0) after 2m13s
[00:42:00.612] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:20:0)
[00:42:00.661] Build status: STARTED
[00:42:00.661] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:21:0) after 2m38s
[00:44:38.668] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:21:0)
[00:44:38.704] Build status: STARTED
[00:44:38.704] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:22:0) after 2m7s
[00:46:45.732] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:22:0)
[00:46:45.767] Build status: STARTED
[00:46:45.767] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:23:0) after 9m4s
[00:55:49.806] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:23:0)
[00:55:49.841] Build status: STARTED
[00:55:49.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:24:0) after 5m8s
[01:00:57.867] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:24:0)
[01:00:57.899] Build status: STARTED
[01:00:57.899] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:25:0) after 4m53s
[01:05:51.020] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:25:0)
[01:05:51.055] Build status: STARTED
[01:05:51.055] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:26:0) after 3m26s
[01:09:17.392] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:26:0)
[01:09:17.443] Build status: STARTED
[01:09:17.443] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:27:0) after 8m35s
[01:17:52.822] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:27:0)
[01:17:52.854] Build status: STARTED
[01:17:52.854] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:28:0) after 5m43s
[01:23:35.915] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:28:0)
[01:23:35.955] Build status: STARTED
[01:23:35.955] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:29:0) after 8m59s
[01:32:34.982] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:29:0)
[01:32:35.015] Build status: STARTED
[01:32:35.015] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:30:0) after 3m39s
[01:36:14.257] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:30:0)
[01:36:14.287] Build status: STARTED
[01:36:14.287] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:31:0) after 5m15s
[01:41:29.411] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:31:0)
[01:41:29.441] Build status: STARTED
[01:41:29.441] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:32:0) after 1m24s
[01:42:53.463] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:32:0)
[01:42:53.514] Build status: STARTED
[01:42:53.514] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:33:0) after 8m2s
[01:50:55.551] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:33:0)
[01:50:55.586] Build status: STARTED
[01:50:55.586] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:34:0) after 3m54s
[01:54:49.577] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:34:0)
[01:54:49.615] Build status: STARTED
[01:54:49.615] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:35:0) after 6m20s
[02:01:09.944] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:35:0)
[02:01:09.974] Build status: STARTED
[02:01:09.974] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:36:0) after 2m44s
[02:03:53.989] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:36:0)
[02:03:54.024] Build status: STARTED
[02:03:54.024] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:37:0) after 6m33s
[02:10:27.058] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:37:0)
[02:10:27.087] Build status: STARTED
[02:10:27.087] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:38:0) after 3m0s
[02:13:27.284] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:38:0)
[02:13:27.318] Build status: STARTED
[02:13:27.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:39:0) after 9m31s
[02:22:58.343] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:39:0)
[02:22:58.371] Build status: STARTED
[02:22:58.371] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:40:0) after 6m39s
[02:29:37.396] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:40:0)
[02:29:37.429] Build status: STARTED
[02:29:37.429] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:41:0) after 8m6s
[02:37:43.428] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:41:0)
[02:37:43.469] Build status: STARTED
[02:37:43.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:42:0) after 9m43s
[02:47:26.440] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:42:0)
[02:47:26.469] Build status: STARTED
[02:47:26.469] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:43:0) after 5m25s
[02:52:51.844] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:43:0)
[02:52:51.918] Build status: STARTED
[02:52:51.918] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:44:0) after 5m2s
[02:57:54.107] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:44:0)
[02:57:54.138] Build status: STARTED
[02:57:54.138] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:45:0) after 6m26s
[03:04:20.199] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:45:0)
[03:04:20.240] Build status: STARTED
[03:04:20.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:46:0) after 5m28s
[03:09:48.291] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:46:0)
[03:09:48.333] Build status: STARTED
[03:09:48.333] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:47:0) after 7m51s
[03:17:39.571] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:47:0)
[03:17:39.693] Build status: STARTED
[03:17:39.693] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:48:0) after 9m43s
[03:27:22.795] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:48:0)
[03:27:22.826] Build status: STARTED
[03:27:22.826] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:49:0) after 5m33s
[03:32:56.004] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:49:0)
[03:32:56.043] Build status: STARTED
[03:32:56.043] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:50:0) after 7m10s
[03:40:06.171] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:50:0)
[03:40:06.201] Build status: STARTED
[03:40:06.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroid x86:9006577491186778960:51:0) after 7m56s
[03:45:57.514] Received PubSub notification, asking Buildbucket for the build status
[03:45:57.547] Build:
{
"id": "8841147429106885760",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroid x86"
},
"number": 1481,
"createdBy": "project:chromium",
"createTime": "2021-07-20T22:56:31.507796Z",
"startTime": "2021-07-20T22:56:46.232310Z",
"endTime": "2021-07-21T03:45:57.233776155Z",
"updateTime": "2021-07-21T03:45:57.234102053Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "02412c76b611678b2d450bbac32f42f50ec2bb7b",
"ref": "refs/heads/main"
}
}
}
[03:45:57.547] Invocation finished in 4h49m27.407494117s with status SUCCEEDED