[17:22:35.826] New invocation is queued and will start shortly
[17:22:36.885] Starting the invocation (attempt 1)
[17:22:36.911] Popped buildset tag "buildset:commit/gitiles/android.googlesource.com/platform/art/+/0e759267235d7ae4ff63e65fddc06dc85ca506aa"
[17:22:36.911] Popped gitiles commit info from properties and tags
[17:22:36.911] Preparing PubSub topic for "https://cr-buildbucket.appspot.com"
[17:22:36.911] PubSub topic is "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
[17:22:36.912] Buildbucket request:
{
"requestId": "8913374220268473024",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host-x86_64-poison-debug"
},
"properties": {
"$recipe_engine/scheduler": {
"hostname": "luci-scheduler.appspot.com",
"invocation": "8913374220268473024",
"job": "art/host-x86_64-poison-debug",
"triggers": [
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "0795fe298810b3cf2008491fce2965a241962ec6"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@0795fe298810b3cf2008491fce2965a241962ec6",
"title": "0795fe298810b3cf2008491fce2965a241962ec6",
"url": "https://android.googlesource.com/platform/libcore/+/0795fe298810b3cf2008491fce2965a241962ec6"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "ae57b1792c1135edc05ac2500e36e9271ffe08b9"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@ae57b1792c1135edc05ac2500e36e9271ffe08b9",
"title": "ae57b1792c1135edc05ac2500e36e9271ffe08b9",
"url": "https://android.googlesource.com/platform/libcore/+/ae57b1792c1135edc05ac2500e36e9271ffe08b9"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "6599788873acd441fcecd52ddac27d6f0a19aa42"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@6599788873acd441fcecd52ddac27d6f0a19aa42",
"title": "6599788873acd441fcecd52ddac27d6f0a19aa42",
"url": "https://android.googlesource.com/platform/libcore/+/6599788873acd441fcecd52ddac27d6f0a19aa42"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "28f38f7513029c006ba71a5e74a66d3341cdf461"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@28f38f7513029c006ba71a5e74a66d3341cdf461",
"title": "28f38f7513029c006ba71a5e74a66d3341cdf461",
"url": "https://android.googlesource.com/platform/libcore/+/28f38f7513029c006ba71a5e74a66d3341cdf461"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "61c570a4aa5601e5a3d4489d7e25a71a81fdd504"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@61c570a4aa5601e5a3d4489d7e25a71a81fdd504",
"title": "61c570a4aa5601e5a3d4489d7e25a71a81fdd504",
"url": "https://android.googlesource.com/platform/libcore/+/61c570a4aa5601e5a3d4489d7e25a71a81fdd504"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "6f6e558c3409b147d11ffa673c4a64bfd1ffd654"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@6f6e558c3409b147d11ffa673c4a64bfd1ffd654",
"title": "6f6e558c3409b147d11ffa673c4a64bfd1ffd654",
"url": "https://android.googlesource.com/platform/libcore/+/6f6e558c3409b147d11ffa673c4a64bfd1ffd654"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/libcore",
"revision": "b234a2bfdefda705c92814d97edd9284c972f8e0"
},
"id": "https://android.googlesource.com/platform/libcore/+/refs/heads/master@b234a2bfdefda705c92814d97edd9284c972f8e0",
"title": "b234a2bfdefda705c92814d97edd9284c972f8e0",
"url": "https://android.googlesource.com/platform/libcore/+/b234a2bfdefda705c92814d97edd9284c972f8e0"
},
{
"gitiles": {
"ref": "refs/heads/master",
"repo": "https://android.googlesource.com/platform/art",
"revision": "0e759267235d7ae4ff63e65fddc06dc85ca506aa"
},
"id": "https://android.googlesource.com/platform/art/+/refs/heads/master@0e759267235d7ae4ff63e65fddc06dc85ca506aa",
"title": "0e759267235d7ae4ff63e65fddc06dc85ca506aa",
"url": "https://android.googlesource.com/platform/art/+/0e759267235d7ae4ff63e65fddc06dc85ca506aa"
}
]
}
},
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "0e759267235d7ae4ff63e65fddc06dc85ca506aa",
"ref": "refs/heads/master"
},
"tags": [
{
"key": "scheduler_invocation_id",
"value": "8913374220268473024"
},
{
"key": "scheduler_job_id",
"value": "art/host-x86_64-poison-debug"
},
{
"key": "user_agent",
"value": "luci-scheduler"
}
],
"notify": {
"pubsubTopic": "projects/luci-scheduler/topics/scheduler.buildbucket.cr-buildbucket~appspot.gserviceaccount.com"
}
}
[17:22:37.637] Scheduled build:
{
"id": "8747944158363948225",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host-x86_64-poison-debug"
},
"number": 9499,
"createdBy": "project:art",
"createTime": "2024-05-14T17:22:37.020710900Z",
"updateTime": "2024-05-14T17:22:37.020710900Z",
"status": "SCHEDULED",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "0e759267235d7ae4ff63e65fddc06dc85ca506aa",
"ref": "refs/heads/master"
}
},
"canary": true
}
[17:22:37.637] Task URL: https://cr-buildbucket.appspot.com/build/8747944158363948225
[17:22:37.637] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:2:0) after 6m25s
[17:22:38.684] Received PubSub notification, asking Buildbucket for the build status
[17:22:38.765] Build status: SCHEDULED
[17:22:46.271] Received PubSub notification, asking Buildbucket for the build status
[17:22:46.303] Build status: STARTED
[17:29:02.695] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:2:0)
[17:29:02.732] Build status: STARTED
[17:29:02.732] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:5:0) after 8m30s
[17:37:32.745] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:5:0)
[17:37:32.766] Build status: STARTED
[17:37:32.766] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:6:0) after 1m14s
[17:38:46.790] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:6:0)
[17:38:46.813] Build status: STARTED
[17:38:46.813] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:7:0) after 5m17s
[17:44:03.926] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:7:0)
[17:44:03.944] Build status: STARTED
[17:44:03.944] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:8:0) after 6m38s
[17:50:41.971] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:8:0)
[17:50:41.990] Build status: STARTED
[17:50:41.990] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:9:0) after 9m48s
[18:00:30.017] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:9:0)
[18:00:30.033] Build status: STARTED
[18:00:30.033] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:10:0) after 9m47s
[18:10:17.058] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:10:0)
[18:10:17.080] Build status: STARTED
[18:10:17.080] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:11:0) after 6m21s
[18:16:38.102] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:11:0)
[18:16:38.130] Build status: STARTED
[18:16:38.131] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:12:0) after 9m5s
[18:25:43.153] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:12:0)
[18:25:43.175] Build status: STARTED
[18:25:43.175] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:13:0) after 1m3s
[18:26:46.191] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:13:0)
[18:26:46.362] Build status: STARTED
[18:26:46.362] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:14:0) after 4m35s
[18:31:21.361] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:14:0)
[18:31:21.387] Build status: STARTED
[18:31:21.387] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:15:0) after 4m48s
[18:36:09.387] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:15:0)
[18:36:09.444] Build status: STARTED
[18:36:09.444] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:16:0) after 8m29s
[18:44:38.555] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:16:0)
[18:44:38.574] Build status: STARTED
[18:44:38.574] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:17:0) after 9m8s
[18:53:46.610] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:17:0)
[18:53:46.643] Build status: STARTED
[18:53:46.643] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:18:0) after 4m18s
[18:58:04.666] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:18:0)
[18:58:04.715] Build status: STARTED
[18:58:04.715] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:19:0) after 8m50s
[19:06:54.731] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:19:0)
[19:06:54.772] Build status: STARTED
[19:06:54.772] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:20:0) after 7m55s
[19:14:49.848] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:20:0)
[19:14:49.874] Build status: STARTED
[19:14:49.874] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:21:0) after 6m43s
[19:21:32.897] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:21:0)
[19:21:32.971] Build status: STARTED
[19:21:32.971] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:22:0) after 4m56s
[19:26:29.018] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:22:0)
[19:26:29.049] Build status: STARTED
[19:26:29.049] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:23:0) after 7m33s
[19:34:02.075] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:23:0)
[19:34:02.112] Build status: STARTED
[19:34:02.112] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:24:0) after 1m33s
[19:35:35.227] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:24:0)
[19:35:35.251] Build status: STARTED
[19:35:35.251] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:25:0) after 5m48s
[19:41:23.250] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:25:0)
[19:41:23.274] Build status: STARTED
[19:41:23.274] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:26:0) after 7m38s
[19:49:01.329] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:26:0)
[19:49:01.401] Build status: STARTED
[19:49:01.401] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:27:0) after 2m38s
[19:51:39.446] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:27:0)
[19:51:39.466] Build status: STARTED
[19:51:39.466] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:28:0) after 8m12s
[19:59:51.493] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:28:0)
[19:59:51.553] Build status: STARTED
[19:59:51.553] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:29:0) after 1m54s
[20:01:45.590] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:29:0)
[20:01:45.974] Build status: STARTED
[20:01:45.974] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:30:0) after 3m7s
[20:04:53.022] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:30:0)
[20:04:53.050] Build status: STARTED
[20:04:53.050] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:31:0) after 3m31s
[20:08:24.067] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:31:0)
[20:08:24.099] Build status: STARTED
[20:08:24.099] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:32:0) after 9m25s
[20:17:49.123] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:32:0)
[20:17:49.144] Build status: STARTED
[20:17:49.144] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:33:0) after 1m7s
[20:18:56.219] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:33:0)
[20:18:56.237] Build status: STARTED
[20:18:56.237] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:34:0) after 2m58s
[20:21:54.286] Handling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:34:0)
[20:21:54.304] Build status: STARTED
[20:21:54.304] Scheduling timer "check-buildbucket-build-status" (art/host-x86_64-poison-debug:8913374220268473024:35:0) after 6m16s
[20:23:57.813] Received PubSub notification, asking Buildbucket for the build status
[20:23:57.915] Build:
{
"id": "8747944158363948225",
"builder": {
"project": "art",
"bucket": "ci",
"builder": "host-x86_64-poison-debug"
},
"number": 9499,
"createdBy": "project:art",
"createTime": "2024-05-14T17:22:37.020710900Z",
"startTime": "2024-05-14T17:22:46.003953900Z",
"endTime": "2024-05-14T20:23:57.446683713Z",
"updateTime": "2024-05-14T20:23:57.446683713Z",
"status": "SUCCESS",
"input": {
"gitilesCommit": {
"host": "android.googlesource.com",
"project": "platform/art",
"id": "0e759267235d7ae4ff63e65fddc06dc85ca506aa",
"ref": "refs/heads/master"
}
},
"canary": true
}
[20:23:57.915] Invocation finished in 3h1m22.094696612s with status SUCCEEDED