This job view page is being replaced by Spyglass soon. Check out the new job view.
PRliggitt: WIP - Stop ignoring unit test flakes
ResultFAILURE
Tests 2 failed / 9578 succeeded
Started2020-07-31 22:14
Elapsed38m40s
Revision5e1f40da0fd0b22ed733ffecc9f44ad8f8fa99ec
links{u'resultstore': {u'url': u'https://source.cloud.google.com/results/invocations/f0005b46-cac3-458b-92d4-4251f59e6d85/targets/test'}}
resultstorehttps://source.cloud.google.com/results/invocations/f0005b46-cac3-458b-92d4-4251f59e6d85/targets/test
uploadercrier

Test Failures


//pkg/controller/volume/persistentvolume/go_default_test:run_2_of_10 0.00s

bazel test //pkg/controller/volume/persistentvolume/go_default_test:run_2_of_10
exec ${PAGER:-/usr/bin/less} "$0" || exit 1
Executing tests from //pkg/controller/volume/persistentvolume:go_default_test
-----------------------------------------------------------------------------
=== RUN   TestSync
--- PASS: TestSync (0.09s)
=== RUN   TestSyncBlockVolume
E0731 22:46:35.164057      23 pv_controller.go:1432] error finding provisioning plugin for claim default/claim14-5: storageclass.storage.k8s.io "gold" not found
E0731 22:46:35.166216      23 pv_controller.go:1432] error finding provisioning plugin for claim default/claim14-5-1: storageclass.storage.k8s.io "silver" not found
--- PASS: TestSyncBlockVolume (0.04s)
=== RUN   TestMultiSync
--- PASS: TestMultiSync (14.94s)
=== RUN   TestDeleteSync
E0731 22:46:50.138815      23 goroutinemap.go:150] Operation for "delete-volume8-3[]" failed. No retries permitted until 2020-07-31 22:46:50.638616228 +0000 UTC m=+15.722565536 (durationBeforeRetry 500ms). Error: "Error getting deleter volume plugin for volume \"volume8-3\": no volume plugin matched"
E0731 22:46:50.142107      23 goroutinemap.go:150] Operation for "delete-volume8-4[]" failed. No retries permitted until 2020-07-31 22:46:50.642001401 +0000 UTC m=+15.725950717 (durationBeforeRetry 500ms). Error: "Failed to create deleter for volume \"volume8-4\": Mock plugin error: no deleteCalls configured"
E0731 22:46:50.144640      23 goroutinemap.go:150] Operation for "delete-volume8-5[]" failed. No retries permitted until 2020-07-31 22:46:50.644575973 +0000 UTC m=+15.728525280 (durationBeforeRetry 500ms). Error: "Mock delete error"
--- PASS: TestDeleteSync (0.04s)
=== RUN   TestDeleteMultiSync
E0731 22:46:50.177897      23 goroutinemap.go:150] Operation for "delete-volume9-1[]" failed. No retries permitted until 2020-07-31 22:46:50.677836351 +0000 UTC m=+15.761785628 (durationBeforeRetry 500ms). Error: "Mock delete error"
--- PASS: TestDeleteMultiSync (1.89s)
=== RUN   TestMatchVolume
--- PASS: TestMatchVolume (0.00s)
=== RUN   TestMatchingWithBoundVolumes
--- PASS: TestMatchingWithBoundVolumes (0.00s)
=== RUN   TestListByAccessModes
--- PASS: TestListByAccessModes (0.00s)
=== RUN   TestAllPossibleAccessModes
--- PASS: TestAllPossibleAccessModes (0.00s)
=== RUN   TestFindingVolumeWithDifferentAccessModes
--- PASS: TestFindingVolumeWithDifferentAccessModes (0.00s)
=== RUN   TestVolumeModeCheck
=== RUN   TestVolumeModeCheck/pvc_filesystem_and_pv_block
=== RUN   TestVolumeModeCheck/pvc_filesystem_and_pv_filesystem
=== RUN   TestVolumeModeCheck/pvc_filesystem_and_pv_nil
=== RUN   TestVolumeModeCheck/pvc_block_and_pv_filesystem
=== RUN   TestVolumeModeCheck/pvc_nil_and_pv_filesystem
=== RUN   TestVolumeModeCheck/pvc_nil_and_pv_nil
=== RUN   TestVolumeModeCheck/pvc_nil_and_pv_block
=== RUN   TestVolumeModeCheck/pvc_block_and_pv_nil
=== RUN   TestVolumeModeCheck/pvc_block_and_pv_block
--- PASS: TestVolumeModeCheck (0.01s)
    --- PASS: TestVolumeModeCheck/pvc_filesystem_and_pv_block (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_filesystem_and_pv_filesystem (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_filesystem_and_pv_nil (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_block_and_pv_filesystem (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_nil_and_pv_filesystem (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_nil_and_pv_nil (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_nil_and_pv_block (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_block_and_pv_nil (0.00s)
    --- PASS: TestVolumeModeCheck/pvc_block_and_pv_block (0.00s)
=== RUN   TestFilteringVolumeModes
=== RUN   TestFilteringVolumeModes/pvc_filesystem_and_pv_block
=== RUN   TestFilteringVolumeModes/pvc_block_and_pv_no_mode_with_default_filesystem
=== RUN   TestFilteringVolumeModes/pvc_no_mode_defaulted_to_filesystem_and_pv_block
=== RUN   TestFilteringVolumeModes/pvc_block_and_pv_block
=== RUN   TestFilteringVolumeModes/pvc_filesystem_and_pv_filesystem
=== RUN   TestFilteringVolumeModes/pvc_mode_is_nil_and_defaulted_and_pv_mode_is_nil_and_defaulted
=== RUN   TestFilteringVolumeModes/pvc_block_and_pv_filesystem
--- PASS: TestFilteringVolumeModes (0.01s)
    --- PASS: TestFilteringVolumeModes/pvc_filesystem_and_pv_block (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_block_and_pv_no_mode_with_default_filesystem (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_no_mode_defaulted_to_filesystem_and_pv_block (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_block_and_pv_block (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_filesystem_and_pv_filesystem (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_mode_is_nil_and_defaulted_and_pv_mode_is_nil_and_defaulted (0.00s)
    --- PASS: TestFilteringVolumeModes/pvc_block_and_pv_filesystem (0.00s)
W0731 22:46:52.085890      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.086379      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.087306      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.087808      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.088551      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=false. It will be removed in a future release.
=== RUN   TestStorageObjectInUseProtectionFiltering
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_not_set
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_set
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_not_set
W0731 22:46:52.089070      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.090100      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=false. It will be removed in a future release.
W0731 22:46:52.090582      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_set
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_not_set#01
W0731 22:46:52.091714      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.092270      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_set#01
W0731 22:46:52.094500      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.095164      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
W0731 22:46:52.096543      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=false. It will be removed in a future release.
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_not_set#01
W0731 22:46:52.097520      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
=== RUN   TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_set#01
W0731 22:46:52.098432      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=false. It will be removed in a future release.
W0731 22:46:52.098799      23 feature_gate.go:235] Setting GA feature gate StorageObjectInUseProtection=true. It will be removed in a future release.
--- PASS: TestStorageObjectInUseProtectionFiltering (0.01s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_not_set (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_set (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_not_set (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_set (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_not_set#01 (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_enabled_-_pv_deletionTimeStamp_set#01 (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_not_set#01 (0.00s)
    --- PASS: TestStorageObjectInUseProtectionFiltering/feature_disabled_-_pv_deletionTimeStamp_set#01 (0.00s)
=== RUN   TestFindingPreboundVolumes
--- PASS: TestFindingPreboundVolumes (0.00s)
=== RUN   TestBestMatchDelayed
--- PASS: TestBestMatchDelayed (0.00s)
=== RUN   TestFindMatchVolumeWithNode
--- PASS: TestFindMatchVolumeWithNode (0.00s)
=== RUN   TestCheckAccessModes
--- PASS: TestCheckAccessModes (0.00s)
=== RUN   TestProvisionSync
E0731 22:46:52.110626      23 pv_controller.go:1432] error finding provisioning plugin for claim default/claim11-2: no volume plugin matched name: kubernetes.io/mock-volume
E0731 22:46:52.112754      23 goroutinemap.go:150] Operation for "provision-default/claim11-3[uid11-3]" failed. No retries permitted until 2020-07-31 22:46:52.612682215 +0000 UTC m=+17.696631558 (durationBeforeRetry 500ms). Error: "Mock plugin error: no provisionCalls configured"
E0731 22:46:52.114848      23 goroutinemap.go:150] Operation for "provision-default/claim11-4[uid11-4]" failed. No retries permitted until 2020-07-31 22:46:52.614757307 +0000 UTC m=+17.698706651 (durationBeforeRetry 500ms). Error: "Mock provisioner error"
E0731 22:46:52.195610      23 pv_controller.go:1631] Error finding internal deleter for volume plugin "kubernetes.io/mock-volume"
E0731 22:46:52.225262      23 pv_controller.go:1631] Error finding internal deleter for volume plugin "kubernetes.io/mock-volume"
E0731 22:46:52.254172      23 pv_controller.go:1631] Error finding internal deleter for volume plugin "kubernetes.io/mock-volume"
E0731 22:46:52.258567      23 pv_controller.go:1432] error finding provisioning plugin for claim default/claim11-14: storageclass.storage.k8s.io "non-existing" not found
E0731 22:46:52.263756      23 pv_controller.go:1432] error finding provisioning plugin for claim default/claim11-18: no volume plugin matched name: kubernetes.io/unknown
E0731 22:46:52.273408      23 goroutinemap.go:150] Operation for "provision-default/claim11-20[uid11-20]" failed. No retries permitted until 2020-07-31 22:46:52.773297728 +0000 UTC m=+17.857247027 (durationBeforeRetry 500ms). Error: "provisioner \"kubernetes.io/mock-volume\" doesn't support mount options"
--- PASS: TestProvisionSync (0.19s)
=== RUN   TestProvisionMultiSync
--- PASS: TestProvisionMultiSync (18.74s)
=== RUN   TestDisablingDynamicProvisioner
--- PASS: TestDisablingDynamicProvisioner (0.00s)
=== RUN   TestControllerSync
E0731 22:47:11.037686      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.041479      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.043274      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.043417      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
I0731 22:47:11.043622      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.061301      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.061614      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.062175      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.071125      23 pv_controller_base.go:303] Starting persistent volume controller
E0731 22:47:11.073126      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.073423      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.074278      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.174046      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.189128      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.189706      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.190453      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.191939      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.192234      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.201896      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.206045      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.293219      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.307577      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.308275      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.309078      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.310290      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.310563      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.314805      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.317842      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.414009      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.433341      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.433953      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.434623      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.435495      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.435650      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.443979      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.445342      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.536592      23 shared_informer.go:247] Caches are synced for persistent volume 
    pv_controller_test.go:317: Test "5-6 - delete claim and waiting for external volume deletion" initial test call failed: failed checking timestamp cache: should not be empty
I0731 22:47:11.561320      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.561870      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.562529      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.573485      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.573751      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.575243      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.576712      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.675036      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.703456      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.704029      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.704519      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.706486      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.706578      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.716349      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.716615      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.807467      23 shared_informer.go:247] Caches are synced for persistent volume 
I0731 22:47:11.820133      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.820370      23 pv_controller_base.go:456] volume worker queue shutting down
I0731 22:47:11.820539      23 pv_controller_base.go:513] claim worker queue shutting down
I0731 22:47:11.823519      23 pv_controller_base.go:303] Starting persistent volume controller
I0731 22:47:11.827066      23 shared_informer.go:240] Waiting for caches to sync for persistent volume
E0731 22:47:11.831304      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Node: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"nodes"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
E0731 22:47:11.832480      23 reflector.go:127] k8s.io/client-go/informers/factory.go:134: Failed to watch *v1.Pod: unhandled watch: testing.WatchActionImpl{ActionImpl:testing.ActionImpl{Namespace:"", Verb:"watch", Resource:schema.GroupVersionResource{Group:"", Version:"v1", Resource:"pods"}, Subresource:""}, WatchRestrictions:testing.WatchRestrictions{Labels:labels.internalSelector(nil), Fields:fields.andTerm{}, ResourceVersion:""}}
I0731 22:47:11.928090      23 shared_informer.go:247] Caches are synced for persistent volume 
--- FAIL: TestControllerSync (0.90s)
=== RUN   TestControllerCache
I0731 22:47:11.936414      23 pv_controller_base.go:319] Shutting down persistent volume controller
I0731 22:47:11.937792      23 pv_controller_base.go:513] claim worker queue shutting down
--- PASS: TestControllerCache (0.00s)
I0731 22:47:11.938866      23 pv_controller_base.go:456] volume worker queue shutting down
=== RUN   TestControllerCacheParsingError
--- PASS: TestControllerCacheParsingError (0.00s)
=== RUN   TestDelayBindingMode
--- PASS: TestDelayBindingMode (0.00s)
=== RUN   TestAnnealMigrationAnnotations
=== RUN   TestAnnealMigrationAnnotations/migration_on_for_GCE
=== RUN   TestAnnealMigrationAnnotations/migration_off_for_GCE
=== RUN   TestAnnealMigrationAnnotations/migration_off_for_GCE_removes_migrated_to_(rollback)
=== RUN   TestAnnealMigrationAnnotations/migration_on_for_GCE_other_plugin_not_affected
=== RUN   TestAnnealMigrationAnnotations/not_dynamically_provisioned_migration_off_for_GCE
=== RUN   TestAnnealMigrationAnnotations/not_dynamically_provisioned_migration_on_for_GCE
=== RUN   TestAnnealMigrationAnnotations/nil_annotations_migration_off_for_GCE
=== RUN   TestAnnealMigrationAnnotations/nil_annotations_migration_on_for_GCE
--- PASS: TestAnnealMigrationAnnotations (0.01s)
    --- PASS: TestAnnealMigrationAnnotations/migration_on_for_GCE (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/migration_off_for_GCE (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/migration_off_for_GCE_removes_migrated_to_(rollback) (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/migration_on_for_GCE_other_plugin_not_affected (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/not_dynamically_provisioned_migration_off_for_GCE (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/not_dynamically_provisioned_migration_on_for_GCE (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/nil_annotations_migration_off_for_GCE (0.00s)
    --- PASS: TestAnnealMigrationAnnotations/nil_annotations_migration_on_for_GCE (0.00s)
=== RUN   TestRecycleSync
--- PASS: TestRecycleSync (0.06s)
=== RUN   TestRecycleMultiSync
--- PASS: TestRecycleMultiSync (3.75s)
FAIL

				from junit_bazel.xml

Filter through log files | View test history on testgrid


//staging/src/k8s.io/cloud-provider/controllers/nodelifecycle/go_default_test:run_6_of_10 0.00s

bazel test //staging/src/k8s.io/cloud-provider/controllers/nodelifecycle/go_default_test:run_6_of_10
exec ${PAGER:-/usr/bin/less} "$0" || exit 1
Executing tests from //staging/src/k8s.io/cloud-provider/controllers/nodelifecycle:go_default_test
-----------------------------------------------------------------------------
=== RUN   Test_NodesDeleted
=== RUN   Test_NodesDeleted/node_is_not_ready_and_does_not_exist
W0731 22:45:58.330421      23 node_lifecycle_controller.go:226] Cannot find valid providerID for node name "node0", assuming non existence
==================
WARNING: DATA RACE
Read at 0x00c0003e3cc3 by goroutine 11:
  testing.(*common).logDepth()
      GOROOT/src/testing/testing.go:727 +0xa9
  testing.(*common).log()
      GOROOT/src/testing/testing.go:720 +0x8f
  testing.(*common).Logf()
      GOROOT/src/testing/testing.go:766 +0x21
  testing.(*common).Logf-fm()
      GOROOT/src/testing/testing.go:766 +0x73
  k8s.io/kubernetes/vendor/k8s.io/client-go/tools/record.(*eventBroadcasterImpl).StartLogging.func1()
      staging/src/k8s.io/client-go/tools/record/event.go:282 +0x261
  k8s.io/kubernetes/vendor/k8s.io/client-go/tools/record.(*eventBroadcasterImpl).StartEventWatcher.func1()
      staging/src/k8s.io/client-go/tools/record/event.go:308 +0x141

Previous write at 0x00c0003e3cc3 by goroutine 9:
  testing.tRunner.func1()
      GOROOT/src/testing/testing.go:1094 +0x484
  testing.tRunner()
      GOROOT/src/testing/testing.go:1112 +0x22a

Goroutine 11 (running) created at:
  k8s.io/kubernetes/vendor/k8s.io/client-go/tools/record.(*eventBroadcasterImpl).StartEventWatcher()
      staging/src/k8s.io/client-go/tools/record/event.go:299 +0x8f
  k8s.io/kubernetes/vendor/k8s.io/client-go/tools/record.(*eventBroadcasterImpl).StartLogging()
      staging/src/k8s.io/client-go/tools/record/event.go:280 +0x9c
  k8s.io/kubernetes/vendor/k8s.io/cloud-provider/controllers/nodelifecycle.Test_NodesDeleted.func1()
      staging/src/k8s.io/cloud-provider/controllers/nodelifecycle/node_lifecycle_controller_test.go:293 +0x524
  testing.tRunner()
      GOROOT/src/testing/testing.go:1108 +0x202

Goroutine 9 (running) created at:
  testing.(*T).Run()
      GOROOT/src/testing/testing.go:1159 +0x796
  k8s.io/kubernetes/vendor/k8s.io/cloud-provider/controllers/nodelifecycle.Test_NodesDeleted()
      staging/src/k8s.io/cloud-provider/controllers/nodelifecycle/node_lifecycle_controller_test.go:275 +0x31e4
  testing.tRunner()
      GOROOT/src/testing/testing.go:1108 +0x202
==================
=== RUN   Test_NodesDeleted/node_is_not_ready_and_provider_returns_err
E0731 22:45:58.333288      23 node_lifecycle_controller.go:153] error checking if node node0 exists: err!
=== RUN   Test_NodesDeleted/node_is_not_ready_but_still_exists
=== RUN   Test_NodesDeleted/node_ready_condition_is_unknown,_node_doesn't_exist
W0731 22:45:58.336658      23 node_lifecycle_controller.go:226] Cannot find valid providerID for node name "node0", assuming non existence
=== RUN   Test_NodesDeleted/node_ready_condition_is_unknown,_node_exists
=== RUN   Test_NodesDeleted/node_is_ready,_but_provider_said_it_is_deleted_(maybe_a_bug_in_provider)
=== CONT  Test_NodesDeleted
    testing.go:1023: race detected during execution of test
--- FAIL: Test_NodesDeleted (0.01s)
    --- PASS: Test_NodesDeleted/node_is_not_ready_and_does_not_exist (0.00s)
    event.go:282: Event(v1.ObjectReference{Kind:"Node", Namespace:"", Name:"node0", UID:"", APIVersion:"", ResourceVersion:"", FieldPath:""}): type: 'Normal' reason: 'Deleting node node0 because it does not exist in the cloud provider' Node node0 event: DeletingNode
    --- PASS: Test_NodesDeleted/node_is_not_ready_and_provider_returns_err (0.00s)
    --- PASS: Test_NodesDeleted/node_is_not_ready_but_still_exists (0.00s)
    --- PASS: Test_NodesDeleted/node_ready_condition_is_unknown,_node_doesn't_exist (0.00s)
    event.go:282: Event(v1.ObjectReference{Kind:"Node", Namespace:"", Name:"node0", UID:"", APIVersion:"", ResourceVersion:"", FieldPath:""}): type: 'Normal' reason: 'Deleting node node0 because it does not exist in the cloud provider' Node node0 event: DeletingNode
    --- PASS: Test_NodesDeleted/node_ready_condition_is_unknown,_node_exists (0.00s)
    --- PASS: Test_NodesDeleted/node_is_ready,_but_provider_said_it_is_deleted_(maybe_a_bug_in_provider) (0.00s)
=== RUN   Test_NodesShutdown
=== RUN   Test_NodesShutdown/node_is_not_ready_and_was_shutdown,_but_exists
=== RUN   Test_NodesShutdown/node_is_not_ready,_but_there_is_error_checking_if_node_is_shutdown
W0731 22:45:58.351169      23 node_lifecycle_controller.go:226] Cannot find valid providerID for node name "node0", assuming non existence
=== RUN   Test_NodesShutdown/node_is_not_ready_and_is_not_shutdown
I0731 22:45:58.351802      23 event.go:282] Event(v1.ObjectReference{Kind:"Node", Namespace:"", Name:"node0", UID:"", APIVersion:"", ResourceVersion:"", FieldPath:""}): type: 'Normal' reason: 'Deleting node node0 because it does not exist in the cloud provider' Node node0 event: DeletingNode
W0731 22:45:58.353162      23 node_lifecycle_controller.go:226] Cannot find valid providerID for node name "node0", assuming non existence
I0731 22:45:58.353682      23 event.go:282] Event(v1.ObjectReference{Kind:"Node", Namespace:"", Name:"node0", UID:"", APIVersion:"", ResourceVersion:"", FieldPath:""}): type: 'Normal' reason: 'Deleting node node0 because it does not exist in the cloud provider' Node node0 event: DeletingNode
=== RUN   Test_NodesShutdown/node_is_ready_but_provider_says_it's_shutdown_(maybe_a_bug_by_provider)
=== RUN   Test_NodesShutdown/node_is_shutdown_but_provider_says_it_does_not_exist
W0731 22:45:58.356441      23 node_lifecycle_controller.go:226] Cannot find valid providerID for node name "node0", assuming non existence
I0731 22:45:58.357231      23 event.go:282] Event(v1.ObjectReference{Kind:"Node", Namespace:"", Name:"node0", UID:"", APIVersion:"", ResourceVersion:"", FieldPath:""}): type: 'Normal' reason: 'Deleting node node0 because it does not exist in the cloud provider' Node node0 event: DeletingNode
--- PASS: Test_NodesShutdown (0.02s)
    --- PASS: Test_NodesShutdown/node_is_not_ready_and_was_shutdown,_but_exists (0.01s)
    --- PASS: Test_NodesShutdown/node_is_not_ready,_but_there_is_error_checking_if_node_is_shutdown (0.00s)
    --- PASS: Test_NodesShutdown/node_is_not_ready_and_is_not_shutdown (0.00s)
    --- PASS: Test_NodesShutdown/node_is_ready_but_provider_says_it's_shutdown_(maybe_a_bug_by_provider) (0.00s)
    --- PASS: Test_NodesShutdown/node_is_shutdown_but_provider_says_it_does_not_exist (0.00s)
=== CONT  
    testing.go:1023: race detected during execution of test
FAIL

				from junit_bazel.xml

Filter through log files | View test history on testgrid


Show 9578 Passed Tests