This job view page is being replaced by Spyglass soon. Check out the new job view.
PRkarlkfi: fix: Sort reconcile events to fix flakey test
ResultFAILURE
Tests 0 failed / 0 succeeded
Started2022-04-20 19:32
Elapsed12m7s
Revisionab12585897100962e1b0f502948bd43e1386b60a
Refs 581

No Test Failures!


Error lines from build-log.txt

... skipping 502 lines ...
I0420 19:35:37.038053   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:35:37.038106   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:35:37.043168   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:35:37.043212   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:35:37.043280   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:35:37.043370   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 2)
I0420 19:35:37.043467   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-41868824_pod2__Pod", Error: "annotation prevents deletion (\"client.lifecycle.config.k8s.io/deletion\": \"detach\")" }
I0420 19:35:37.043544   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-41868824_pod1__Pod", Error: "annotation prevents deletion (\"cli-utils.sigs.k8s.io/on-remove\": \"keep\")" }
I0420 19:35:37.043591   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:35:37.043607   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:35:37.043616   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Started" }
I0420 19:35:37.043640   18524 inv_set_task.go:59] inventory set task starting (name: "inventory-set-0")
I0420 19:35:37.043675   18524 inv_set_task.go:123] inventory set task completing (name: "inventory-set-0")
I0420 19:35:37.043729   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Finished" }
... skipping 24 lines ...
I0420 19:35:37.082912   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Successful", Identifier: "e2e-test-41868824_nginx-deployment_apps_Deployment" }
I0420 19:35:37.082926   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:35:37.082944   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:35:37.082959   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:35:37.083031   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:35:37.083105   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 2)
I0420 19:35:37.088781   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-41868824_pod2__Pod", Error: "annotation prevents deletion (\"client.lifecycle.config.k8s.io/deletion\": \"detach\")" }
I0420 19:35:37.094207   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-41868824_pod1__Pod", Error: "annotation prevents deletion (\"cli-utils.sigs.k8s.io/on-remove\": \"keep\")" }
I0420 19:35:37.094271   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:35:37.094286   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:35:37.094330   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:35:37.094362   18524 task.go:94] wait task starting (name: "wait-1", objects: 2)
I0420 19:35:37.094374   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-41868824_pod2__Pod" }
I0420 19:35:37.094419   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-41868824_pod1__Pod" }
... skipping 125 lines ...
I0420 19:35:45.937602   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:35:45.937721   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:35:45.945979   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:35:45.946035   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:35:45.946062   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:35:45.946089   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 2)
I0420 19:35:45.952278   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Failed", Identifier: "_invalidexamples.cli-utils.example.io_apiextensions.k8s.io_CustomResourceDefinition", Error: "error when creating \"unstructured\": CustomResourceDefinition.apiextensions.k8s.io \"invalidexamples.cli-utils.example.io\" is invalid: [spec.versions: Invalid value: []apiextensions.CustomResourceDefinitionVersion(nil): must have exactly one version marked as storage version, status.storedVersions: Invalid value: []string(nil): must have at least one stored version]" }
I0420 19:35:45.963665   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:35:45.963738   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:35:45.963763   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:35:45.963772   18524 task.go:94] wait task starting (name: "wait-0", objects: 2)
I0420 19:35:45.963784   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "_invalidexamples.cli-utils.example.io_apiextensions.k8s.io_CustomResourceDefinition" }
I0420 19:35:45.963822   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Pending", Identifier: "e2e-test-94151820_pod1__Pod" }
... skipping 312 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns1",
                          Name: "pod1",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns1",
                          Name: "pod3",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns2",
                          Name: "pod2",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 116 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns1",
                          Name: "pod1",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns1",
                          Name: "pod3",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-44959896-ns2",
                          Name: "pod2",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 92 lines ...
            			Identifier: object.ObjMetadata{
            				Namespace: "",
          - 				Name:      "e2e-test-44959896-ns2",
          + 				Name:      "e2e-test-44959896-ns1",
            				GroupKind: {Kind: "Namespace"},
            			},
            			Error: nil,
            		},
            		WaitEvent:       nil,
            		ValidationEvent: nil,
            	},
            	{
            		... // 5 identical fields
... skipping 5 lines ...
            			Identifier: object.ObjMetadata{
            				Namespace: "",
          - 				Name:      "e2e-test-44959896-ns1",
          + 				Name:      "e2e-test-44959896-ns2",
            				GroupKind: {Kind: "Namespace"},
            			},
            			Error: nil,
            		},
            		WaitEvent:       nil,
            		ValidationEvent: nil,
            	},
            	{EventType: s"ActionGroupType", ActionGroupEvent: &{GroupName: "prune-3", Action: s"Delete", Type: s"Finished"}},
            	{EventType: s"ActionGroupType", ActionGroupEvent: &{GroupName: "wait-3", Action: s"Wait"}},
... skipping 156 lines ...
I0420 19:37:06.996008   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:37:06.996401   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:37:07.008554   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:37:07.008587   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:37:07.008602   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:37:07.008643   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:37:07.013765   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-92591885_pod1__Pod", Error: "dependency scheduled for delete: e2e-test-92591885_pod2__Pod" }
I0420 19:37:07.013832   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:37:07.013844   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:37:07.013860   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:37:07.013866   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:37:07.013872   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-92591885_pod1__Pod" }
I0420 19:37:07.013883   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:37:07.013903   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:37:07.013915   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:37:07.013921   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:37:07.013943   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:37:07.013980   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-92591885_pod2__Pod", Error: "dependent scheduled for apply: e2e-test-92591885_pod1__Pod" }
I0420 19:37:07.014037   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:37:07.014045   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:37:07.014086   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:37:07.014124   18524 task.go:94] wait task starting (name: "wait-1", objects: 1)
I0420 19:37:07.014142   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-92591885_pod2__Pod" }
I0420 19:37:07.014161   18524 task.go:186] all objects reconciled or skipped (name: "wait-1")
... skipping 65 lines ...
I0420 19:37:11.231314   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:37:11.231416   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:37:11.244877   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:37:11.244932   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:37:11.244964   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:37:11.245002   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:37:11.255812   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-74519164-ns1_pod-b__Pod", Error: "dependency scheduled for delete: _e2e-test-74519164-ns1__Namespace" }
I0420 19:37:11.256065   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:37:11.256233   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:37:11.256381   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:37:11.256435   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:37:11.256447   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-74519164-ns1_pod-b__Pod" }
I0420 19:37:11.256499   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:37:11.256530   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:37:11.256572   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:37:11.256621   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:37:11.256644   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:37:11.256687   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "_e2e-test-74519164-ns1__Namespace", Error: "namespace still in use: e2e-test-74519164-ns1" }
I0420 19:37:11.256729   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:37:11.256737   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:37:11.256744   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:37:11.256751   18524 task.go:94] wait task starting (name: "wait-1", objects: 1)
I0420 19:37:11.256759   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "_e2e-test-74519164-ns1__Namespace" }
I0420 19:37:11.256770   18524 task.go:186] all objects reconciled or skipped (name: "wait-1")
... skipping 119 lines ...
I0420 19:37:42.276961   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:37:42.321964   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:37:42.322341   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:37:42.322543   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:37:42.322660   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:37:42.322860   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Pending", Identifier: "e2e-test-49917828_pod1__Pod" }
I0420 19:37:58.139587   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Failed", Identifier: "e2e-test-49917828_pod1__Pod" }
I0420 19:37:58.139842   18524 task.go:407] all objects reconciled or skipped (name: "wait-0")
I0420 19:37:58.139863   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:37:58.139871   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:37:58.139902   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Started" }
I0420 19:37:58.139932   18524 inv_set_task.go:59] inventory set task starting (name: "inventory-set-0")
I0420 19:37:58.146020   18524 inv_set_task.go:123] inventory set task completing (name: "inventory-set-0")
... skipping 56 lines ...
I0420 19:38:28.536037   18524 depends.go:144] adding vertex: e2e-test-78553593_pod1__Pod
I0420 19:38:28.536077   18524 depends.go:144] adding vertex: e2e-test-78553593_nginx-deployment_apps_Deployment
I0420 19:38:28.536083   18524 depends.go:144] adding vertex: e2e-test-78553593_pod3__Pod
I0420 19:38:28.536087   18524 depends.go:144] adding vertex: e2e-test-78553593_pod-a__Pod
I0420 19:38:28.536091   18524 depends.go:144] adding vertex: e2e-test-78553593_pod-b__Pod
I0420 19:38:28.536109   18524 depends.go:258] adding edge from: e2e-test-78553593_nginx-deployment_apps_Deployment, to: e2e-test-78553593_pod1__Pod
I0420 19:38:28.536125   18524 depends.go:255] failed to add edges: invalid "config.kubernetes.io/depends-on" annotation: external dependency: /namespaces/e2e-test-78553593/Pod/pod3 -> /namespaces/e2e-test-78553593/Pod/pod0
I0420 19:38:28.536339   18524 depends.go:191] adding edge from: e2e-test-78553593_pod-a__Pod, to: e2e-test-78553593_pod-b__Pod
I0420 19:38:28.536468   18524 depends.go:191] adding edge from: e2e-test-78553593_pod-b__Pod, to: e2e-test-78553593_pod-a__Pod
I0420 19:38:28.536483   18524 depends.go:188] failed to add edges: invalid "config.kubernetes.io/apply-time-mutation" annotation: external dependency: /namespaces/e2e-test-78553593/Pod/pod-b -> /Pod/pod-a
I0420 19:38:28.536521   18524 solver.go:159] adding inventory add task (2 objects)
I0420 19:38:28.536534   18524 solver.go:243] adding apply task (1 objects)
I0420 19:38:28.536549   18524 solver.go:265] adding wait task
I0420 19:38:28.536554   18524 solver.go:243] adding apply task (1 objects)
I0420 19:38:28.536559   18524 solver.go:265] adding wait task
I0420 19:38:28.536564   18524 solver.go:216] adding inventory set task
... skipping 33 lines ...
STEP: verify podA not found
STEP: verify podB not found
STEP: modify deployment1 depends-on annotation to be invalid
STEP: destroy valid objects and skip invalid objects
I0420 19:38:40.625443   18524 depends.go:144] adding vertex: e2e-test-78553593_nginx-deployment_apps_Deployment
I0420 19:38:40.625485   18524 depends.go:144] adding vertex: e2e-test-78553593_pod1__Pod
I0420 19:38:40.625541   18524 depends.go:217] failed to add edges from: e2e-test-78553593_nginx-deployment_apps_Deployment: invalid "config.kubernetes.io/depends-on" annotation: failed to parse object reference (index: 0): expected 3 or 5 fields, found 1: "invalid"
I0420 19:38:40.625639   18524 solver.go:282] adding prune task (1 objects)
I0420 19:38:40.625693   18524 solver.go:265] adding wait task
I0420 19:38:40.625737   18524 solver.go:226] adding delete inventory task
I0420 19:38:40.625812   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Delete", Type: "Started" }
I0420 19:38:40.625892   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:38:40.637462   18524 context.go:66] Sending event: DeleteEvent{ GroupName: "prune-0", Status: "Successful", Identifier: "e2e-test-78553593_pod1__Pod" }
... skipping 118 lines ...
I0420 19:38:57.477003   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:38:57.541977   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:38:57.542020   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:38:57.542040   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:38:57.542076   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:38:57.557513   18524 context.go:66] Sending event: StatusEvent{ Status: "Current", Generation: 1, Identifier: "e2e-test-15287791_nginx-deployment_apps_Deployment" }
I0420 19:38:57.557847   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-15287791_nginx-deployment_apps_Deployment", Error: "inventory policy prevented actuation (strategy: Apply, status: NoMatch, policy: MustMatch)" }
I0420 19:38:57.557895   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:38:57.557908   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:38:57.557922   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:38:57.557931   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:38:57.557945   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-15287791_nginx-deployment_apps_Deployment" }
I0420 19:38:57.557967   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
... skipping 462 lines ...
I0420 19:39:54.909310   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:39:54.909573   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:39:54.920676   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:39:54.921141   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:39:54.921357   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:39:54.921695   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 2)
I0420 19:39:54.921978   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-95844655_pod2__Pod", Error: "annotation prevents deletion (\"client.lifecycle.config.k8s.io/deletion\": \"detach\")" }
I0420 19:39:54.922309   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-95844655_pod1__Pod", Error: "annotation prevents deletion (\"cli-utils.sigs.k8s.io/on-remove\": \"keep\")" }
I0420 19:39:54.922505   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:39:54.922720   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:39:54.923048   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Started" }
I0420 19:39:54.923443   18524 inv_set_task.go:59] inventory set task starting (name: "inventory-set-0")
I0420 19:39:54.923897   18524 inv_set_task.go:123] inventory set task completing (name: "inventory-set-0")
I0420 19:39:54.924024   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Finished" }
... skipping 24 lines ...
I0420 19:39:55.043943   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Successful", Identifier: "e2e-test-95844655_nginx-deployment_apps_Deployment" }
I0420 19:39:55.044012   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:39:55.044070   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:39:55.044123   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:39:55.044165   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:39:55.044227   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 2)
I0420 19:39:55.063548   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-95844655_pod2__Pod", Error: "annotation prevents deletion (\"client.lifecycle.config.k8s.io/deletion\": \"detach\")" }
I0420 19:39:55.073022   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-95844655_pod1__Pod", Error: "annotation prevents deletion (\"cli-utils.sigs.k8s.io/on-remove\": \"keep\")" }
I0420 19:39:55.073089   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:39:55.073100   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:39:55.073113   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:39:55.073120   18524 task.go:94] wait task starting (name: "wait-1", objects: 2)
I0420 19:39:55.073135   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-95844655_pod2__Pod" }
I0420 19:39:55.073146   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-95844655_pod1__Pod" }
... skipping 125 lines ...
I0420 19:40:05.237312   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:40:05.238152   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:40:05.321709   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:40:05.321978   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:40:05.322121   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:40:05.322324   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 2)
I0420 19:40:05.336105   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Failed", Identifier: "_invalidexamples.cli-utils.example.io_apiextensions.k8s.io_CustomResourceDefinition", Error: "error when creating \"unstructured\": CustomResourceDefinition.apiextensions.k8s.io \"invalidexamples.cli-utils.example.io\" is invalid: [spec.versions: Invalid value: []apiextensions.CustomResourceDefinitionVersion(nil): must have exactly one version marked as storage version, status.storedVersions: Invalid value: []string(nil): must have at least one stored version]" }
I0420 19:40:05.376423   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:40:05.376735   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:40:05.376937   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:40:05.377048   18524 task.go:94] wait task starting (name: "wait-0", objects: 2)
I0420 19:40:05.377162   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "_invalidexamples.cli-utils.example.io_apiextensions.k8s.io_CustomResourceDefinition" }
I0420 19:40:05.377318   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Pending", Identifier: "e2e-test-80130905_pod1__Pod" }
... skipping 313 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns1",
                          Name: "pod1",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns1",
                          Name: "pod3",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns2",
                          Name: "pod2",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 116 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns1",
                          Name: "pod1",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns1",
                          Name: "pod3",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 96 lines ...
                      Status: 1,
                      Identifier: {
                          Namespace: "e2e-test-75516743-ns2",
                          Name: "pod2",
                          GroupKind: {Group: "", Kind: "Pod"},
                      },
                      Error: nil,
                  },
                  WaitEvent: nil,
                  ValidationEvent: nil,
              },
              {
                  EventType: 2,
... skipping 92 lines ...
            			Identifier: object.ObjMetadata{
            				Namespace: "",
          - 				Name:      "e2e-test-75516743-ns2",
          + 				Name:      "e2e-test-75516743-ns1",
            				GroupKind: {Kind: "Namespace"},
            			},
            			Error: nil,
            		},
            		WaitEvent:       nil,
            		ValidationEvent: nil,
            	},
            	{
            		... // 5 identical fields
... skipping 5 lines ...
            			Identifier: object.ObjMetadata{
            				Namespace: "",
          - 				Name:      "e2e-test-75516743-ns1",
          + 				Name:      "e2e-test-75516743-ns2",
            				GroupKind: {Kind: "Namespace"},
            			},
            			Error: nil,
            		},
            		WaitEvent:       nil,
            		ValidationEvent: nil,
            	},
            	{EventType: s"ActionGroupType", ActionGroupEvent: &{GroupName: "prune-3", Action: s"Delete", Type: s"Finished"}},
            	{EventType: s"ActionGroupType", ActionGroupEvent: &{GroupName: "wait-3", Action: s"Wait"}},
... skipping 156 lines ...
I0420 19:41:56.805387   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:41:56.805549   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:41:56.822588   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:41:56.822686   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:41:56.822708   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:41:56.822807   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:41:56.826616   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-26384543_pod1__Pod", Error: "dependency scheduled for delete: e2e-test-26384543_pod2__Pod" }
I0420 19:41:56.826687   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:41:56.826701   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:41:56.826738   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:41:56.826749   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:41:56.826759   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-26384543_pod1__Pod" }
I0420 19:41:56.826776   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:41:56.826816   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:41:56.826841   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:41:56.826851   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:41:56.826875   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:41:56.826912   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "e2e-test-26384543_pod2__Pod", Error: "dependent scheduled for apply: e2e-test-26384543_pod1__Pod" }
I0420 19:41:56.826958   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:41:56.826968   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:41:56.826978   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:41:56.827002   18524 task.go:94] wait task starting (name: "wait-1", objects: 1)
I0420 19:41:56.827016   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "e2e-test-26384543_pod2__Pod" }
I0420 19:41:56.827027   18524 task.go:186] all objects reconciled or skipped (name: "wait-1")
... skipping 74 lines ...
I0420 19:42:13.062305   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:42:13.062395   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:42:13.077808   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:42:13.077862   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:42:13.077880   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:42:13.077922   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:42:13.080657   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-80297720-ns1_pod-b__Pod", Error: "dependency scheduled for delete: _e2e-test-80297720-ns1__Namespace" }
I0420 19:42:13.080707   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:42:13.080718   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:42:13.080745   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:42:13.080776   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:42:13.080785   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-80297720-ns1_pod-b__Pod" }
I0420 19:42:13.080811   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
I0420 19:42:13.080840   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:42:13.080848   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:42:13.080853   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Started" }
I0420 19:42:13.080880   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:42:13.080940   18524 context.go:66] Sending event: PruneEvent{ GroupName: "prune-0", Status: "Skipped", Identifier: "_e2e-test-80297720-ns1__Namespace", Error: "namespace still in use: e2e-test-80297720-ns1" }
I0420 19:42:13.080981   18524 prune_task.go:74] prune task completing (name: "prune-0")
I0420 19:42:13.080987   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Prune", Type: "Finished" }
I0420 19:42:13.080992   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-1", Action: "Wait", Type: "Started" }
I0420 19:42:13.080999   18524 task.go:94] wait task starting (name: "wait-1", objects: 1)
I0420 19:42:13.081003   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-1", Status: "Skipped", Identifier: "_e2e-test-80297720-ns1__Namespace" }
I0420 19:42:13.081010   18524 task.go:186] all objects reconciled or skipped (name: "wait-1")
... skipping 128 lines ...
I0420 19:42:33.552642   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:42:33.568657   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:42:33.568693   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:42:33.568715   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:42:33.568722   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:42:33.568739   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Pending", Identifier: "e2e-test-40428753_pod1__Pod" }
I0420 19:42:49.543782   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Failed", Identifier: "e2e-test-40428753_pod1__Pod" }
I0420 19:42:49.543889   18524 task.go:407] all objects reconciled or skipped (name: "wait-0")
I0420 19:42:49.543948   18524 task.go:116] wait task completing (name: "wait-0",): context canceled
I0420 19:42:49.543968   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Finished" }
I0420 19:42:49.543994   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Started" }
I0420 19:42:49.544034   18524 inv_set_task.go:59] inventory set task starting (name: "inventory-set-0")
I0420 19:42:49.553819   18524 inv_set_task.go:123] inventory set task completing (name: "inventory-set-0")
... skipping 56 lines ...
I0420 19:43:19.934413   18524 depends.go:144] adding vertex: e2e-test-92865641_pod1__Pod
I0420 19:43:19.934461   18524 depends.go:144] adding vertex: e2e-test-92865641_nginx-deployment_apps_Deployment
I0420 19:43:19.934467   18524 depends.go:144] adding vertex: e2e-test-92865641_pod3__Pod
I0420 19:43:19.934471   18524 depends.go:144] adding vertex: e2e-test-92865641_pod-a__Pod
I0420 19:43:19.934475   18524 depends.go:144] adding vertex: e2e-test-92865641_pod-b__Pod
I0420 19:43:19.934505   18524 depends.go:258] adding edge from: e2e-test-92865641_nginx-deployment_apps_Deployment, to: e2e-test-92865641_pod1__Pod
I0420 19:43:19.934529   18524 depends.go:255] failed to add edges: invalid "config.kubernetes.io/depends-on" annotation: external dependency: /namespaces/e2e-test-92865641/Pod/pod3 -> /namespaces/e2e-test-92865641/Pod/pod0
I0420 19:43:19.934830   18524 depends.go:191] adding edge from: e2e-test-92865641_pod-a__Pod, to: e2e-test-92865641_pod-b__Pod
I0420 19:43:19.935067   18524 depends.go:191] adding edge from: e2e-test-92865641_pod-b__Pod, to: e2e-test-92865641_pod-a__Pod
I0420 19:43:19.935096   18524 depends.go:188] failed to add edges: invalid "config.kubernetes.io/apply-time-mutation" annotation: external dependency: /namespaces/e2e-test-92865641/Pod/pod-b -> /Pod/pod-a
I0420 19:43:19.935164   18524 solver.go:159] adding inventory add task (2 objects)
I0420 19:43:19.935227   18524 solver.go:243] adding apply task (1 objects)
I0420 19:43:19.935283   18524 solver.go:265] adding wait task
I0420 19:43:19.935310   18524 solver.go:243] adding apply task (1 objects)
I0420 19:43:19.935336   18524 solver.go:265] adding wait task
I0420 19:43:19.935386   18524 solver.go:216] adding inventory set task
... skipping 33 lines ...
STEP: verify podA not found
STEP: verify podB not found
STEP: modify deployment1 depends-on annotation to be invalid
STEP: destroy valid objects and skip invalid objects
I0420 19:43:26.013353   18524 depends.go:144] adding vertex: e2e-test-92865641_pod1__Pod
I0420 19:43:26.013397   18524 depends.go:144] adding vertex: e2e-test-92865641_nginx-deployment_apps_Deployment
I0420 19:43:26.013417   18524 depends.go:217] failed to add edges from: e2e-test-92865641_nginx-deployment_apps_Deployment: invalid "config.kubernetes.io/depends-on" annotation: failed to parse object reference (index: 0): expected 3 or 5 fields, found 1: "invalid"
I0420 19:43:26.013443   18524 solver.go:282] adding prune task (1 objects)
I0420 19:43:26.013453   18524 solver.go:265] adding wait task
I0420 19:43:26.013460   18524 solver.go:226] adding delete inventory task
I0420 19:43:26.013507   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "prune-0", Action: "Delete", Type: "Started" }
I0420 19:43:26.013536   18524 prune_task.go:55] prune task starting (name: "prune-0", objects: 1)
I0420 19:43:26.024140   18524 context.go:66] Sending event: DeleteEvent{ GroupName: "prune-0", Status: "Successful", Identifier: "e2e-test-92865641_pod1__Pod" }
... skipping 125 lines ...
I0420 19:43:38.357594   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Started" }
I0420 19:43:38.357857   18524 inv_add_task.go:48] inventory add task starting (name: "inventory-add-0")
I0420 19:43:38.375301   18524 inv_add_task.go:94] inventory add task completing (name: "inventory-add-0")
I0420 19:43:38.375606   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-add-0", Action: "Inventory", Type: "Finished" }
I0420 19:43:38.375909   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Started" }
I0420 19:43:38.376053   18524 apply_task.go:91] apply task starting (name: "apply-0", objects: 1)
I0420 19:43:38.380426   18524 context.go:66] Sending event: ApplyEvent{ GroupName: "apply-0", Status: "Skipped", Identifier: "e2e-test-12570828_nginx-deployment_apps_Deployment", Error: "inventory policy prevented actuation (strategy: Apply, status: NoMatch, policy: MustMatch)" }
I0420 19:43:38.380489   18524 apply_task.go:222] apply task completing (name: "apply-0")
I0420 19:43:38.380504   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "apply-0", Action: "Apply", Type: "Finished" }
I0420 19:43:38.380523   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "wait-0", Action: "Wait", Type: "Started" }
I0420 19:43:38.380531   18524 task.go:94] wait task starting (name: "wait-0", objects: 1)
I0420 19:43:38.380545   18524 context.go:66] Sending event: WaitEvent{ GroupName: "wait-0", Status: "Skipped", Identifier: "e2e-test-12570828_nginx-deployment_apps_Deployment" }
I0420 19:43:38.380566   18524 task.go:186] all objects reconciled or skipped (name: "wait-0")
... skipping 166 lines ...
I0420 19:44:06.724178   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Started" }
I0420 19:44:06.724196   18524 inv_set_task.go:59] inventory set task starting (name: "inventory-set-0")
STEP: Verify inventory
I0420 19:44:06.735748   18524 inv_set_task.go:123] inventory set task completing (name: "inventory-set-0")
I0420 19:44:06.735787   18524 context.go:66] Sending event: ActionGroupEvent{ GroupName: "inventory-set-0", Action: "Inventory", Type: "Finished" }
STEP: Apply second set of resources, using same inventory name but different ID
STEP: Verify that we get the correct error

• [SLOW TEST:8.073 seconds]
Applier
/home/prow/go/src/sigs.k8s.io/cli-utils/test/e2e/e2e_test.go:49
  NameStrategy
  /home/prow/go/src/sigs.k8s.io/cli-utils/test/e2e/e2e_test.go:236
    InventoryIDMismatch
    /home/prow/go/src/sigs.k8s.io/cli-utils/test/e2e/e2e_test.go:258
------------------------------


Summarizing 2 Failures:

[Fail] Applier InventoryConfigMap Basic [It] DependsOn 
/home/prow/go/src/sigs.k8s.io/cli-utils/test/e2e/depends_on_test.go:732

[Fail] Applier InventoryCustom Basic [It] DependsOn 
/home/prow/go/src/sigs.k8s.io/cli-utils/test/e2e/depends_on_test.go:732

Ran 39 of 39 Specs in 558.670 seconds
FAIL! -- 37 Passed | 2 Failed | 0 Pending | 0 Skipped
--- FAIL: TestE2e (558.68s)
FAIL

Ginkgo ran 1 suite in 10m14.719849999s
Test Suite Failed
make: *** [Makefile:73: test-e2e] Error 1
+ EXIT_VALUE=2
+ set +o xtrace
Cleaning up after docker in docker.
================================================================================
Cleaning up after docker
d799bf67ce08
... skipping 4 lines ...