[11:25:06.680] New invocation is queued and will start shortly
[11:25:07.882] Starting the invocation (attempt 1)
[11:25:07.918] Popped buildset tag "buildset:commit/gitiles/chromium.googlesource.com/chromium/src/+/0c2e236a85b937e242f0e3ab5433a2e24680a900"
[11:25:07.918] Popped gitiles commit info from properties and tags
[11:25:07.918] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[11:25:07.918] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[11:25:07.918] Buildbucket request:
{
"requestId": "8982703390664145040",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"properties": {
},
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c2e236a85b937e242f0e3ab5433a2e24680a900",
"ref": "refs/heads/main"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8982703390664145040"
},
{
"key": "scheduler_job_id",
"value": "chromium/ToTAndroidCoverage x86"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[11:25:08.427] Scheduled build:
{
"id": "8817273328602664353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"number": 3356,
"createdBy": "project:chromium",
"createTime": "2022-04-10T11:25:07.985825347Z",
"updateTime": "2022-04-10T11:25:07.985825347Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c2e236a85b937e242f0e3ab5433a2e24680a900",
"ref": "refs/heads/main"
}
}
}
[11:25:08.427] Task URL: https://cr-buildbucket.appspot.com/build/8817273328602664353
[11:25:08.427] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:2:0) after 6m36s
[11:25:44.735] Received PubSub notification, asking Buildbucket for the build status
[11:25:44.777] Build status: STARTED
[11:31:44.653] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:2:0)
[11:31:44.674] Build status: STARTED
[11:31:44.674] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:4:0) after 5m51s
[11:37:35.691] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:4:0)
[11:37:35.713] Build status: STARTED
[11:37:35.713] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:5:0) after 9m8s
[11:46:43.733] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:5:0)
[11:46:43.774] Build status: STARTED
[11:46:43.774] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:6:0) after 9m37s
[11:56:20.840] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:6:0)
[11:56:20.864] Build status: STARTED
[11:56:20.864] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:7:0) after 1m19s
[11:57:39.889] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:7:0)
[11:57:39.909] Build status: STARTED
[11:57:39.909] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:8:0) after 8m53s
[12:06:32.928] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:8:0)
[12:06:32.969] Build status: STARTED
[12:06:32.969] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:9:0) after 6m55s
[12:13:28.212] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:9:0)
[12:13:28.232] Build status: STARTED
[12:13:28.232] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:10:0) after 6m55s
[12:20:23.251] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:10:0)
[12:20:23.273] Build status: STARTED
[12:20:23.273] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:11:0) after 2m25s
[12:22:48.290] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:11:0)
[12:22:48.309] Build status: STARTED
[12:22:48.309] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:12:0) after 2m33s
[12:25:21.364] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:12:0)
[12:25:21.386] Build status: STARTED
[12:25:21.386] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:13:0) after 5m55s
[12:31:16.405] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:13:0)
[12:31:16.434] Build status: STARTED
[12:31:16.434] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:14:0) after 1m51s
[12:33:07.469] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:14:0)
[12:33:07.491] Build status: STARTED
[12:33:07.491] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:15:0) after 7m27s
[12:40:34.555] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:15:0)
[12:40:34.581] Build status: STARTED
[12:40:34.581] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:16:0) after 8m11s
[12:48:45.598] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:16:0)
[12:48:45.619] Build status: STARTED
[12:48:45.619] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:17:0) after 3m50s
[12:52:35.637] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:17:0)
[12:52:35.660] Build status: STARTED
[12:52:35.660] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:18:0) after 5m38s
[12:58:14.271] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:18:0)
[12:58:14.294] Build status: STARTED
[12:58:14.294] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:19:0) after 9m42s
[13:07:56.431] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:19:0)
[13:07:56.467] Build status: STARTED
[13:07:56.467] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:20:0) after 6m8s
[13:14:04.485] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:20:0)
[13:14:04.521] Build status: STARTED
[13:14:04.521] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:21:0) after 6m40s
[13:20:44.556] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:21:0)
[13:20:44.603] Build status: STARTED
[13:20:44.603] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:22:0) after 9m32s
[13:30:16.618] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:22:0)
[13:30:16.640] Build status: STARTED
[13:30:16.640] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:23:0) after 2m46s
[13:33:02.657] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:23:0)
[13:33:02.675] Build status: STARTED
[13:33:02.675] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:24:0) after 6m50s
[13:39:52.693] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:24:0)
[13:39:52.721] Build status: STARTED
[13:39:52.721] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:25:0) after 2m44s
[13:42:36.752] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:25:0)
[13:42:36.776] Build status: STARTED
[13:42:36.776] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:26:0) after 3m4s
[13:45:40.849] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:26:0)
[13:45:40.869] Build status: STARTED
[13:45:40.869] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:27:0) after 6m21s
[13:52:01.853] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:27:0)
[13:52:01.886] Build status: STARTED
[13:52:01.886] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:28:0) after 7m13s
[13:59:14.905] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:28:0)
[13:59:14.926] Build status: STARTED
[13:59:14.926] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:29:0) after 5m17s
[14:04:31.947] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:29:0)
[14:04:31.979] Build status: STARTED
[14:04:31.979] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:30:0) after 3m41s
[14:08:13.057] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:30:0)
[14:08:13.085] Build status: STARTED
[14:08:13.085] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:31:0) after 4m37s
[14:12:50.110] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:31:0)
[14:12:50.152] Build status: STARTED
[14:12:50.152] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:32:0) after 2m53s
[14:15:43.279] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:32:0)
[14:15:43.303] Build status: STARTED
[14:15:43.303] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:33:0) after 6m13s
[14:21:56.289] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:33:0)
[14:21:56.318] Build status: STARTED
[14:21:56.318] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:34:0) after 8m34s
[14:30:30.368] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:34:0)
[14:30:30.385] Build status: STARTED
[14:30:30.385] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:35:0) after 7m41s
[14:38:11.465] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:35:0)
[14:38:11.502] Build status: STARTED
[14:38:11.502] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:36:0) after 7m23s
[14:45:34.546] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:36:0)
[14:45:34.583] Build status: STARTED
[14:45:34.583] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:37:0) after 1m39s
[14:47:13.826] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:37:0)
[14:47:13.847] Build status: STARTED
[14:47:13.847] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:38:0) after 4m33s
[14:51:47.215] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:38:0)
[14:51:47.233] Build status: STARTED
[14:51:47.233] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:39:0) after 3m37s
[14:55:24.254] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:39:0)
[14:55:24.284] Build status: STARTED
[14:55:24.284] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:40:0) after 7m36s
[15:03:00.313] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:40:0)
[15:03:00.338] Build status: STARTED
[15:03:00.338] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:41:0) after 8m11s
[15:11:11.357] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:41:0)
[15:11:11.578] Build status: STARTED
[15:11:11.578] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:42:0) after 1m19s
[15:12:30.722] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:42:0)
[15:12:30.865] Build status: STARTED
[15:12:30.865] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:43:0) after 3m35s
[15:16:06.088] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:43:0)
[15:16:06.143] Build status: STARTED
[15:16:06.143] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:44:0) after 9m9s
[15:25:15.785] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:44:0)
[15:25:15.809] Build status: STARTED
[15:25:15.809] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:45:0) after 9m12s
[15:34:27.960] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:45:0)
[15:34:27.989] Build status: STARTED
[15:34:27.989] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:46:0) after 4m54s
[15:39:22.040] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:46:0)
[15:39:22.060] Build status: STARTED
[15:39:22.060] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:47:0) after 4m14s
[15:43:36.084] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:47:0)
[15:43:36.161] Build status: STARTED
[15:43:36.161] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:48:0) after 8m1s
[15:51:37.172] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:48:0)
[15:51:37.201] Build status: STARTED
[15:51:37.201] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:49:0) after 3m51s
[15:55:28.222] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:49:0)
[15:55:28.240] Build status: STARTED
[15:55:28.240] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:50:0) after 6m28s
[16:01:56.342] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:50:0)
[16:01:56.366] Build status: STARTED
[16:01:56.366] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:51:0) after 7m45s
[16:09:41.485] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:51:0)
[16:09:41.507] Build status: STARTED
[16:09:41.507] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:52:0) after 3m52s
[16:13:33.526] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:52:0)
[16:13:33.559] Build status: STARTED
[16:13:33.559] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:53:0) after 9m18s
[16:22:51.714] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:53:0)
[16:22:51.740] Build status: STARTED
[16:22:51.740] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:54:0) after 4m19s
[16:27:10.771] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:54:0)
[16:27:10.840] Build status: STARTED
[16:27:10.840] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:55:0) after 7m59s
[16:35:09.866] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:55:0)
[16:35:10.252] Build status: STARTED
[16:35:10.252] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:56:0) after 9m49s
[16:44:59.277] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:56:0)
[16:44:59.311] Build status: STARTED
[16:44:59.311] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:57:0) after 5m4s
[16:50:04.355] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:57:0)
[16:50:04.374] Build status: STARTED
[16:50:04.374] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:58:0) after 4m28s
[16:54:32.518] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:58:0)
[16:54:32.551] Build status: STARTED
[16:54:32.551] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:59:0) after 1m59s
[16:56:31.572] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:59:0)
[16:56:31.599] Build status: STARTED
[16:56:31.599] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:60:0) after 7m50s
[17:04:21.793] Handling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:60:0)
[17:04:21.841] Build status: STARTED
[17:04:21.841] Scheduling timer "check-buildbucket-build-status" (chromium/ToTAndroidCoverage x86:8982703390664145040:61:0) after 8m39s
[17:04:25.880] Received PubSub notification, asking Buildbucket for the build status
[17:04:25.897] Build:
{
"id": "8817273328602664353",
"builder": {
"project": "chromium",
"bucket": "ci",
"builder": "ToTAndroidCoverage x86"
},
"number": 3356,
"createdBy": "project:chromium",
"createTime": "2022-04-10T11:25:07.985825347Z",
"startTime": "2022-04-10T11:25:44.069245Z",
"endTime": "2022-04-10T17:04:25.373704734Z",
"updateTime": "2022-04-10T17:04:25.373704734Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "chromium.googlesource.com",
"project": "chromium/src",
"id": "0c2e236a85b937e242f0e3ab5433a2e24680a900",
"ref": "refs/heads/main"
}
}
}
[17:04:25.897] Invocation finished in 5h39m19.261126207s with status SUCCEEDED