{"level":"warn","ts":"2026-05-08T02:28:52.753188Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:28:52.753599Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.185:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.185:2380","--initial-cluster=instance=https://199.19.213.185:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.185:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.185:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-08T02:28:52.757354Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-08T02:28:52.757409Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-05-08T02:28:52.757446Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T02:28:52.758346Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"]} {"level":"info","ts":"2026-05-08T02:28:52.758449Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.185:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-08T02:28:52.769172Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.436842ms"} {"level":"info","ts":"2026-05-08T02:28:52.778636Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcbda34f8b503a3","cluster-id":"2e806bc52dde46af"} {"level":"info","ts":"2026-05-08T02:28:52.778727Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-08T02:28:52.77876Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 0"} {"level":"info","ts":"2026-05-08T02:28:52.778774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcbda34f8b503a3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-08T02:28:52.778783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became follower at term 1"} {"level":"info","ts":"2026-05-08T02:28:52.778829Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"warn","ts":"2026-05-08T02:28:52.787376Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-08T02:28:52.789161Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-08T02:28:52.790576Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-08T02:28:52.794301Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcbda34f8b503a3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-08T02:28:52.794534Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcbda34f8b503a3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-08T02:28:52.795129Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:28:52.795205Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:28:52.795271Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-08T02:28:52.795619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 switched to configuration voters=(994128063811093411)"} {"level":"info","ts":"2026-05-08T02:28:52.799905Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","added-peer-id":"dcbda34f8b503a3","added-peer-peer-urls":["https://199.19.213.185:2380"]} {"level":"info","ts":"2026-05-08T02:28:52.803215Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-08T02:28:52.803315Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-05-08T02:28:52.803355Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.185:2380"} {"level":"info","ts":"2026-05-08T02:28:52.803663Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcbda34f8b503a3","initial-advertise-peer-urls":["https://199.19.213.185:2380"],"listen-peer-urls":["https://199.19.213.185:2380"],"advertise-client-urls":["https://199.19.213.185:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.185:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-08T02:28:52.803711Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-08T02:28:53.380079Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-08T02:28:53.380162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-08T02:28:53.380208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgPreVoteResp from dcbda34f8b503a3 at term 1"} {"level":"info","ts":"2026-05-08T02:28:53.380229Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became candidate at term 2"} {"level":"info","ts":"2026-05-08T02:28:53.38024Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 received MsgVoteResp from dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-05-08T02:28:53.380256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcbda34f8b503a3 became leader at term 2"} {"level":"info","ts":"2026-05-08T02:28:53.380269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcbda34f8b503a3 elected leader dcbda34f8b503a3 at term 2"} {"level":"info","ts":"2026-05-08T02:28:53.381383Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:28:53.38156Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcbda34f8b503a3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.185:2379]}","request-path":"/0/members/dcbda34f8b503a3/attributes","cluster-id":"2e806bc52dde46af","publish-timeout":"7s"} {"level":"info","ts":"2026-05-08T02:28:53.381561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:28:53.38179Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-08T02:28:53.382839Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"2e806bc52dde46af","local-member-id":"dcbda34f8b503a3","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:28:53.382971Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:28:53.383Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-08T02:28:53.384802Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.185:2379"} {"level":"info","ts":"2026-05-08T02:28:53.385849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-08T02:28:53.386334Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-08T02:28:53.386442Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-08T02:29:03.488771Z","caller":"traceutil/trace.go:171","msg":"trace[127413646] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:371; }","duration":"165.773529ms","start":"2026-05-08T02:29:03.322966Z","end":"2026-05-08T02:29:03.488739Z","steps":["trace[127413646] 'read index received' (duration: 37.440854ms)","trace[127413646] 'applied index is now lower than readState.Index' (duration: 128.331545ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:03.488835Z","caller":"traceutil/trace.go:171","msg":"trace[623102469] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"184.300583ms","start":"2026-05-08T02:29:03.304467Z","end":"2026-05-08T02:29:03.488768Z","steps":["trace[623102469] 'process raft request' (duration: 156.306709ms)","trace[623102469] 'compare' (duration: 27.666111ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:03.488934Z","caller":"traceutil/trace.go:171","msg":"trace[2008017662] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"179.859475ms","start":"2026-05-08T02:29:03.309062Z","end":"2026-05-08T02:29:03.488921Z","steps":["trace[2008017662] 'process raft request' (duration: 179.607324ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:03.489179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.182835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-05-08T02:29:03.489291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.659676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T02:29:03.493268Z","caller":"traceutil/trace.go:171","msg":"trace[1469436835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"119.680187ms","start":"2026-05-08T02:29:03.373555Z","end":"2026-05-08T02:29:03.493235Z","steps":["trace[1469436835] 'agreement among raft nodes before linearized reading' (duration: 115.557711ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:03.489362Z","caller":"traceutil/trace.go:171","msg":"trace[1577322894] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:365; }","duration":"166.408534ms","start":"2026-05-08T02:29:03.32293Z","end":"2026-05-08T02:29:03.489338Z","steps":["trace[1577322894] 'agreement among raft nodes before linearized reading' (duration: 166.168405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.040599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.226783ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:04.040891Z","caller":"traceutil/trace.go:171","msg":"trace[577306490] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:379; }","duration":"505.248017ms","start":"2026-05-08T02:29:03.535622Z","end":"2026-05-08T02:29:04.04087Z","steps":["trace[577306490] 'read index received' (duration: 3.704618ms)","trace[577306490] 'applied index is now lower than readState.Index' (duration: 501.542299ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:04.040885Z","caller":"traceutil/trace.go:171","msg":"trace[1634894241] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"505.779839ms","start":"2026-05-08T02:29:03.535059Z","end":"2026-05-08T02:29:04.040839Z","steps":["trace[1634894241] 'process raft request' (duration: 198.703252ms)","trace[1634894241] 'compare' (duration: 306.087097ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:04.041099Z","caller":"traceutil/trace.go:171","msg":"trace[827329737] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"503.544229ms","start":"2026-05-08T02:29:03.5375Z","end":"2026-05-08T02:29:04.041044Z","steps":["trace[827329737] 'process raft request' (duration: 503.229126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.041203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.535038Z","time spent":"506.007958ms","remote":"127.0.0.1:50478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:29:04.04134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.681455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"info","ts":"2026-05-08T02:29:04.041413Z","caller":"traceutil/trace.go:171","msg":"trace[684777038] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:373; }","duration":"505.804949ms","start":"2026-05-08T02:29:03.535595Z","end":"2026-05-08T02:29:04.0414Z","steps":["trace[684777038] 'agreement among raft nodes before linearized reading' (duration: 505.664874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.041504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.535585Z","time spent":"505.907374ms","remote":"127.0.0.1:50858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2861,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-08T02:29:04.041544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.537487Z","time spent":"503.732396ms","remote":"127.0.0.1:50836","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:04.154447Z","caller":"traceutil/trace.go:171","msg":"trace[1642997750] linearizableReadLoop","detail":"{readStateIndex:383; appliedIndex:382; }","duration":"113.445013ms","start":"2026-05-08T02:29:04.040965Z","end":"2026-05-08T02:29:04.15441Z","steps":["trace[1642997750] 'read index received' (duration: 106.430051ms)","trace[1642997750] 'applied index is now lower than readState.Index' (duration: 7.013622ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:04.154603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.376707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-05-08T02:29:04.154606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.672121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:29:04.154675Z","caller":"traceutil/trace.go:171","msg":"trace[1551344874] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"438.502483ms","start":"2026-05-08T02:29:03.716152Z","end":"2026-05-08T02:29:04.154655Z","steps":["trace[1551344874] 'agreement among raft nodes before linearized reading' (duration: 438.338396ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.154689Z","caller":"traceutil/trace.go:171","msg":"trace[1108318322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:374; }","duration":"213.830477ms","start":"2026-05-08T02:29:03.940839Z","end":"2026-05-08T02:29:04.154669Z","steps":["trace[1108318322] 'agreement among raft nodes before linearized reading' (duration: 213.607828ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.154717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.716127Z","time spent":"438.583016ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":207,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-05-08T02:29:04.154732Z","caller":"traceutil/trace.go:171","msg":"trace[356339195] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"609.364446ms","start":"2026-05-08T02:29:03.545338Z","end":"2026-05-08T02:29:04.154703Z","steps":["trace[356339195] 'process raft request' (duration: 602.119854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.154768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.020633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-08T02:29:04.154833Z","caller":"traceutil/trace.go:171","msg":"trace[1405084837] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:374; }","duration":"617.106746ms","start":"2026-05-08T02:29:03.537713Z","end":"2026-05-08T02:29:04.15482Z","steps":["trace[1405084837] 'agreement among raft nodes before linearized reading' (duration: 617.023303ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.154709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"594.470967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:29:04.154887Z","caller":"traceutil/trace.go:171","msg":"trace[1918520959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:374; }","duration":"594.717748ms","start":"2026-05-08T02:29:03.560161Z","end":"2026-05-08T02:29:04.154879Z","steps":["trace[1918520959] 'agreement among raft nodes before linearized reading' (duration: 594.450857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.154884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.5377Z","time spent":"617.17507ms","remote":"127.0.0.1:50608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":229,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"warn","ts":"2026-05-08T02:29:04.154914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.560148Z","time spent":"594.758319ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:29:04.154902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:03.545302Z","time spent":"609.502472ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:04.155103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.824867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2839"} {"level":"info","ts":"2026-05-08T02:29:04.155154Z","caller":"traceutil/trace.go:171","msg":"trace[1018396797] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:374; }","duration":"109.90418ms","start":"2026-05-08T02:29:04.045236Z","end":"2026-05-08T02:29:04.15514Z","steps":["trace[1018396797] 'agreement among raft nodes before linearized reading' (duration: 109.821217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:04.155482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.15685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-05-08T02:29:04.155533Z","caller":"traceutil/trace.go:171","msg":"trace[705491984] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:374; }","duration":"107.239214ms","start":"2026-05-08T02:29:04.048282Z","end":"2026-05-08T02:29:04.155521Z","steps":["trace[705491984] 'agreement among raft nodes before linearized reading' (duration: 106.949482ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.367435Z","caller":"traceutil/trace.go:171","msg":"trace[37645508] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"204.244735ms","start":"2026-05-08T02:29:04.163154Z","end":"2026-05-08T02:29:04.367399Z","steps":["trace[37645508] 'process raft request' (duration: 192.359087ms)","trace[37645508] 'compare' (duration: 11.406189ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:04.367721Z","caller":"traceutil/trace.go:171","msg":"trace[1876402578] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"204.391942ms","start":"2026-05-08T02:29:04.163295Z","end":"2026-05-08T02:29:04.367687Z","steps":["trace[1876402578] 'process raft request' (duration: 203.989205ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.367841Z","caller":"traceutil/trace.go:171","msg":"trace[162226637] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"204.531567ms","start":"2026-05-08T02:29:04.163296Z","end":"2026-05-08T02:29:04.367827Z","steps":["trace[162226637] 'process raft request' (duration: 204.123891ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.3679Z","caller":"traceutil/trace.go:171","msg":"trace[245947858] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"201.535876ms","start":"2026-05-08T02:29:04.166342Z","end":"2026-05-08T02:29:04.367877Z","steps":["trace[245947858] 'process raft request' (duration: 201.13002ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.367979Z","caller":"traceutil/trace.go:171","msg":"trace[1036183976] linearizableReadLoop","detail":"{readStateIndex:389; appliedIndex:384; }","duration":"141.144227ms","start":"2026-05-08T02:29:04.226823Z","end":"2026-05-08T02:29:04.367967Z","steps":["trace[1036183976] 'read index received' (duration: 128.583472ms)","trace[1036183976] 'applied index is now lower than readState.Index' (duration: 12.559045ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:04.368114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.857861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-05-08T02:29:04.368159Z","caller":"traceutil/trace.go:171","msg":"trace[2084009447] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:380; }","duration":"204.946103ms","start":"2026-05-08T02:29:04.163202Z","end":"2026-05-08T02:29:04.368148Z","steps":["trace[2084009447] 'agreement among raft nodes before linearized reading' (duration: 204.803497ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:04.368268Z","caller":"traceutil/trace.go:171","msg":"trace[1428524043] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"201.824998ms","start":"2026-05-08T02:29:04.16643Z","end":"2026-05-08T02:29:04.368255Z","steps":["trace[1428524043] 'process raft request' (duration: 201.241654ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:05.515063Z","caller":"traceutil/trace.go:171","msg":"trace[1166338602] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"107.00938ms","start":"2026-05-08T02:29:05.408029Z","end":"2026-05-08T02:29:05.515039Z","steps":["trace[1166338602] 'process raft request' (duration: 106.813102ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:06.574123Z","caller":"traceutil/trace.go:171","msg":"trace[439097914] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"195.221843ms","start":"2026-05-08T02:29:06.378877Z","end":"2026-05-08T02:29:06.574098Z","steps":["trace[439097914] 'process raft request' (duration: 193.973082ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:06.68687Z","caller":"traceutil/trace.go:171","msg":"trace[528409470] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"102.708782ms","start":"2026-05-08T02:29:06.584138Z","end":"2026-05-08T02:29:06.686847Z","steps":["trace[528409470] 'process raft request' (duration: 102.131039ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:08.859736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.396844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:29:08.859813Z","caller":"traceutil/trace.go:171","msg":"trace[1973364213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:409; }","duration":"469.53137ms","start":"2026-05-08T02:29:08.390264Z","end":"2026-05-08T02:29:08.859795Z","steps":["trace[1973364213] 'range keys from in-memory index tree' (duration: 469.29348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:08.859854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:08.390245Z","time spent":"469.603313ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:29:08.86013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.309837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:08.860302Z","caller":"traceutil/trace.go:171","msg":"trace[1489058354] linearizableReadLoop","detail":"{readStateIndex:421; appliedIndex:420; }","duration":"281.244949ms","start":"2026-05-08T02:29:08.579031Z","end":"2026-05-08T02:29:08.860276Z","steps":["trace[1489058354] 'read index received' (duration: 126.511235ms)","trace[1489058354] 'applied index is now lower than readState.Index' (duration: 154.731284ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:08.860317Z","caller":"traceutil/trace.go:171","msg":"trace[418397754] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"345.246705ms","start":"2026-05-08T02:29:08.515034Z","end":"2026-05-08T02:29:08.86028Z","steps":["trace[418397754] 'process raft request' (duration: 190.624445ms)","trace[418397754] 'compare' (duration: 153.929192ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:08.860433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.406155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T02:29:08.860477Z","caller":"traceutil/trace.go:171","msg":"trace[166555627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:410; }","duration":"281.470698ms","start":"2026-05-08T02:29:08.578993Z","end":"2026-05-08T02:29:08.860463Z","steps":["trace[166555627] 'agreement among raft nodes before linearized reading' (duration: 281.353383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:08.860492Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:08.51501Z","time spent":"345.385221ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:08.982075Z","caller":"traceutil/trace.go:171","msg":"trace[935808896] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"113.965477ms","start":"2026-05-08T02:29:08.868058Z","end":"2026-05-08T02:29:08.982023Z","steps":["trace[935808896] 'process raft request' (duration: 108.607961ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:09.165381Z","caller":"traceutil/trace.go:171","msg":"trace[1308024612] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"144.513138ms","start":"2026-05-08T02:29:09.020832Z","end":"2026-05-08T02:29:09.165345Z","steps":["trace[1308024612] 'process raft request' (duration: 74.63361ms)","trace[1308024612] 'compare' (duration: 69.736413ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:13.986292Z","caller":"traceutil/trace.go:171","msg":"trace[1472681738] linearizableReadLoop","detail":"{readStateIndex:466; appliedIndex:465; }","duration":"253.151978ms","start":"2026-05-08T02:29:13.733113Z","end":"2026-05-08T02:29:13.986265Z","steps":["trace[1472681738] 'read index received' (duration: 252.839756ms)","trace[1472681738] 'applied index is now lower than readState.Index' (duration: 311.212µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:13.986431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.308735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:29:13.98647Z","caller":"traceutil/trace.go:171","msg":"trace[1973136373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:453; }","duration":"253.413629ms","start":"2026-05-08T02:29:13.733043Z","end":"2026-05-08T02:29:13.986456Z","steps":["trace[1973136373] 'agreement among raft nodes before linearized reading' (duration: 253.330406ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:13.986442Z","caller":"traceutil/trace.go:171","msg":"trace[2098116290] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"267.084304ms","start":"2026-05-08T02:29:13.719302Z","end":"2026-05-08T02:29:13.986387Z","steps":["trace[2098116290] 'process raft request' (duration: 266.788722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:15.728108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"715.964905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T02:29:15.728223Z","caller":"traceutil/trace.go:171","msg":"trace[1658365050] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:453; }","duration":"716.134891ms","start":"2026-05-08T02:29:15.012062Z","end":"2026-05-08T02:29:15.728197Z","steps":["trace[1658365050] 'range keys from in-memory index tree' (duration: 715.814958ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:15.728266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.012035Z","time spent":"716.222484ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:29:15.728653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.412071734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:29:15.72875Z","caller":"traceutil/trace.go:171","msg":"trace[2070677978] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:453; }","duration":"1.412352635s","start":"2026-05-08T02:29:14.316373Z","end":"2026-05-08T02:29:15.728726Z","steps":["trace[2070677978] 'range keys from in-memory index tree' (duration: 1.411882176s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:15.728753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.866695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-08T02:29:15.728812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:14.316326Z","time spent":"1.412471619s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:29:15.728818Z","caller":"traceutil/trace.go:171","msg":"trace[1644085264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:453; }","duration":"708.970179ms","start":"2026-05-08T02:29:15.019829Z","end":"2026-05-08T02:29:15.728799Z","steps":["trace[1644085264] 'range keys from in-memory index tree' (duration: 708.729519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:15.729146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.019804Z","time spent":"709.330614ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:29:16.491702Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262226949418102232,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-08T02:29:16.912839Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.176529701s","expected-duration":"1s"} {"level":"info","ts":"2026-05-08T02:29:16.913544Z","caller":"traceutil/trace.go:171","msg":"trace[513786794] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"1.177292122s","start":"2026-05-08T02:29:15.736214Z","end":"2026-05-08T02:29:16.913506Z","steps":["trace[513786794] 'process raft request' (duration: 1.177091334s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:16.913808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.736178Z","time spent":"1.1774866s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:17.71282Z","caller":"traceutil/trace.go:171","msg":"trace[1771779135] linearizableReadLoop","detail":"{readStateIndex:470; appliedIndex:466; }","duration":"1.721448047s","start":"2026-05-08T02:29:15.991339Z","end":"2026-05-08T02:29:17.712787Z","steps":["trace[1771779135] 'read index received' (duration: 921.940595ms)","trace[1771779135] 'applied index is now lower than readState.Index' (duration: 799.506012ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:17.71288Z","caller":"traceutil/trace.go:171","msg":"trace[643582145] transaction","detail":"{read_only:false; response_revision:456; number_of_response:1; }","duration":"1.976037993s","start":"2026-05-08T02:29:15.736798Z","end":"2026-05-08T02:29:17.712836Z","steps":["trace[643582145] 'process raft request' (duration: 1.975915358s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:17.712939Z","caller":"traceutil/trace.go:171","msg":"trace[1610813712] transaction","detail":"{read_only:false; response_revision:457; number_of_response:1; }","duration":"1.853305855s","start":"2026-05-08T02:29:15.859584Z","end":"2026-05-08T02:29:17.712889Z","steps":["trace[1610813712] 'process raft request' (duration: 1.85316631s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:17.713017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.721655395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T02:29:17.713046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.736772Z","time spent":"1.976180309s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:17.713066Z","caller":"traceutil/trace.go:171","msg":"trace[778210098] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:457; }","duration":"1.721784131s","start":"2026-05-08T02:29:15.991268Z","end":"2026-05-08T02:29:17.713052Z","steps":["trace[778210098] 'agreement among raft nodes before linearized reading' (duration: 1.721673836s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:17.71311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.991236Z","time spent":"1.721865784s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-08T02:29:17.713172Z","caller":"traceutil/trace.go:171","msg":"trace[1801023000] transaction","detail":"{read_only:false; response_revision:455; number_of_response:1; }","duration":"1.976904729s","start":"2026-05-08T02:29:15.73626Z","end":"2026-05-08T02:29:17.713165Z","steps":["trace[1801023000] 'process raft request' (duration: 1.976304364s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:17.713217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.736226Z","time spent":"1.976968611s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:17.713208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:15.859558Z","time spent":"1.853497393s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:17.713337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.118063895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.185\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T02:29:17.713365Z","caller":"traceutil/trace.go:171","msg":"trace[150089366] range","detail":"{range_begin:/registry/masterleases/199.19.213.185; range_end:; response_count:1; response_revision:457; }","duration":"1.118133738s","start":"2026-05-08T02:29:16.595224Z","end":"2026-05-08T02:29:17.713358Z","steps":["trace[150089366] 'agreement among raft nodes before linearized reading' (duration: 1.118053335s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:17.713382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:16.595197Z","time spent":"1.11818133s","remote":"127.0.0.1:50434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.185\" "} {"level":"warn","ts":"2026-05-08T02:29:17.713375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"975.067057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:29:17.713467Z","caller":"traceutil/trace.go:171","msg":"trace[1950502033] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:457; }","duration":"975.205453ms","start":"2026-05-08T02:29:16.738239Z","end":"2026-05-08T02:29:17.713445Z","steps":["trace[1950502033] 'agreement among raft nodes before linearized reading' (duration: 975.058727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:17.713567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:16.738203Z","time spent":"975.352898ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:29:17.904506Z","caller":"traceutil/trace.go:171","msg":"trace[1704018700] transaction","detail":"{read_only:false; response_revision:458; number_of_response:1; }","duration":"185.892953ms","start":"2026-05-08T02:29:17.718578Z","end":"2026-05-08T02:29:17.904471Z","steps":["trace[1704018700] 'process raft request' (duration: 176.871496ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:17.914531Z","caller":"traceutil/trace.go:171","msg":"trace[1513165388] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"141.866977ms","start":"2026-05-08T02:29:17.772649Z","end":"2026-05-08T02:29:17.914516Z","steps":["trace[1513165388] 'process raft request' (duration: 141.794954ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:25.531208Z","caller":"traceutil/trace.go:171","msg":"trace[1753602472] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"198.144934ms","start":"2026-05-08T02:29:25.333028Z","end":"2026-05-08T02:29:25.531173Z","steps":["trace[1753602472] 'process raft request' (duration: 197.68638ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:25.685199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.675072ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:25.685358Z","caller":"traceutil/trace.go:171","msg":"trace[808757324] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"128.258325ms","start":"2026-05-08T02:29:25.557084Z","end":"2026-05-08T02:29:25.685342Z","steps":["trace[808757324] 'process raft request' (duration: 128.221505ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:25.68539Z","caller":"traceutil/trace.go:171","msg":"trace[560291852] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"128.552327ms","start":"2026-05-08T02:29:25.556795Z","end":"2026-05-08T02:29:25.685348Z","steps":["trace[560291852] 'process raft request' (duration: 23.653393ms)","trace[560291852] 'compare' (duration: 104.493631ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:26.1796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.817208ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:26.17974Z","caller":"traceutil/trace.go:171","msg":"trace[1138963769] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:491; }","duration":"445.041036ms","start":"2026-05-08T02:29:25.734676Z","end":"2026-05-08T02:29:26.179717Z","steps":["trace[1138963769] 'read index received' (duration: 264.937875ms)","trace[1138963769] 'applied index is now lower than readState.Index' (duration: 180.101531ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:26.179828Z","caller":"traceutil/trace.go:171","msg":"trace[1720487718] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"489.062092ms","start":"2026-05-08T02:29:25.690702Z","end":"2026-05-08T02:29:26.179764Z","steps":["trace[1720487718] 'process raft request' (duration: 308.980432ms)","trace[1720487718] 'compare' (duration: 179.275073ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:26.180236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.50153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:29:26.180317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:25.690679Z","time spent":"489.497986ms","remote":"127.0.0.1:50502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:29:26.180328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.278912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:29:26.180378Z","caller":"traceutil/trace.go:171","msg":"trace[1379517554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:477; }","duration":"431.393064ms","start":"2026-05-08T02:29:25.74897Z","end":"2026-05-08T02:29:26.180363Z","steps":["trace[1379517554] 'agreement among raft nodes before linearized reading' (duration: 431.277813ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:26.180391Z","caller":"traceutil/trace.go:171","msg":"trace[1965290013] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"445.719293ms","start":"2026-05-08T02:29:25.734604Z","end":"2026-05-08T02:29:26.180323Z","steps":["trace[1965290013] 'agreement among raft nodes before linearized reading' (duration: 445.195738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:26.180408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.706475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-08T02:29:26.180469Z","caller":"traceutil/trace.go:171","msg":"trace[441685745] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:477; }","duration":"241.804547ms","start":"2026-05-08T02:29:25.938648Z","end":"2026-05-08T02:29:26.180452Z","steps":["trace[441685745] 'agreement among raft nodes before linearized reading' (duration: 241.682686ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:26.180515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:25.73458Z","time spent":"445.920154ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:29:26.180614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.481674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:29:26.18068Z","caller":"traceutil/trace.go:171","msg":"trace[1920427866] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"431.600786ms","start":"2026-05-08T02:29:25.749069Z","end":"2026-05-08T02:29:26.18067Z","steps":["trace[1920427866] 'agreement among raft nodes before linearized reading' (duration: 431.475955ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:26.180713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:25.749045Z","time spent":"431.659986ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-08T02:29:26.180515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:25.74894Z","time spent":"431.529795ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T02:29:28.105282Z","caller":"traceutil/trace.go:171","msg":"trace[988169765] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"312.875971ms","start":"2026-05-08T02:29:27.792352Z","end":"2026-05-08T02:29:28.105228Z","steps":["trace[988169765] 'process raft request' (duration: 312.605318ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:28.105571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:27.792336Z","time spent":"313.058243ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:28.15217Z","caller":"traceutil/trace.go:171","msg":"trace[516471180] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"177.561512ms","start":"2026-05-08T02:29:27.974558Z","end":"2026-05-08T02:29:28.15212Z","steps":["trace[516471180] 'process raft request' (duration: 177.211528ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.152265Z","caller":"traceutil/trace.go:171","msg":"trace[1210422891] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"168.542462ms","start":"2026-05-08T02:29:27.98367Z","end":"2026-05-08T02:29:28.152213Z","steps":["trace[1210422891] 'process raft request' (duration: 168.29806ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.152612Z","caller":"traceutil/trace.go:171","msg":"trace[1356271482] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"167.495292ms","start":"2026-05-08T02:29:27.985077Z","end":"2026-05-08T02:29:28.152572Z","steps":["trace[1356271482] 'process raft request' (duration: 166.969247ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.152873Z","caller":"traceutil/trace.go:171","msg":"trace[2084471975] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"157.839266ms","start":"2026-05-08T02:29:27.994841Z","end":"2026-05-08T02:29:28.15268Z","steps":["trace[2084471975] 'process raft request' (duration: 157.290301ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153014Z","caller":"traceutil/trace.go:171","msg":"trace[502088176] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"158.1292ms","start":"2026-05-08T02:29:27.994871Z","end":"2026-05-08T02:29:28.153Z","steps":["trace[502088176] 'process raft request' (duration: 157.347062ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153078Z","caller":"traceutil/trace.go:171","msg":"trace[1321195678] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"158.107479ms","start":"2026-05-08T02:29:27.994951Z","end":"2026-05-08T02:29:28.153059Z","steps":["trace[1321195678] 'process raft request' (duration: 157.720235ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153109Z","caller":"traceutil/trace.go:171","msg":"trace[1283090901] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"158.09793ms","start":"2026-05-08T02:29:27.995001Z","end":"2026-05-08T02:29:28.153099Z","steps":["trace[1283090901] 'process raft request' (duration: 157.818697ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153191Z","caller":"traceutil/trace.go:171","msg":"trace[322540400] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"157.893747ms","start":"2026-05-08T02:29:27.995288Z","end":"2026-05-08T02:29:28.153182Z","steps":["trace[322540400] 'process raft request' (duration: 157.625355ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153359Z","caller":"traceutil/trace.go:171","msg":"trace[1918051836] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"146.870338ms","start":"2026-05-08T02:29:28.006453Z","end":"2026-05-08T02:29:28.153323Z","steps":["trace[1918051836] 'process raft request' (duration: 146.589635ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153527Z","caller":"traceutil/trace.go:171","msg":"trace[319440552] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"147.01619ms","start":"2026-05-08T02:29:28.006471Z","end":"2026-05-08T02:29:28.153487Z","steps":["trace[319440552] 'process raft request' (duration: 146.691736ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.153703Z","caller":"traceutil/trace.go:171","msg":"trace[894684] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"129.211585ms","start":"2026-05-08T02:29:28.024371Z","end":"2026-05-08T02:29:28.153582Z","steps":["trace[894684] 'process raft request' (duration: 129.021794ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:28.768395Z","caller":"traceutil/trace.go:171","msg":"trace[1249931484] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"126.407797ms","start":"2026-05-08T02:29:28.641955Z","end":"2026-05-08T02:29:28.768363Z","steps":["trace[1249931484] 'process raft request' (duration: 126.044514ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:29.659452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.526145ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:29.66016Z","caller":"traceutil/trace.go:171","msg":"trace[219188599] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"573.921712ms","start":"2026-05-08T02:29:29.08618Z","end":"2026-05-08T02:29:29.660101Z","steps":["trace[219188599] 'process raft request' (duration: 170.637208ms)","trace[219188599] 'compare' (duration: 402.326943ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:29.660207Z","caller":"traceutil/trace.go:171","msg":"trace[104239813] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"567.157248ms","start":"2026-05-08T02:29:29.093006Z","end":"2026-05-08T02:29:29.660163Z","steps":["trace[104239813] 'process raft request' (duration: 566.901716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:29.660382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:29.092984Z","time spent":"567.29006ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:29.660397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:29.086166Z","time spent":"574.098353ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:29.660478Z","caller":"traceutil/trace.go:171","msg":"trace[274865233] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"520.082051ms","start":"2026-05-08T02:29:29.140371Z","end":"2026-05-08T02:29:29.660453Z","steps":["trace[274865233] 'process raft request' (duration: 519.858999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:29.660579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:29.140335Z","time spent":"520.202773ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:29:29.660605Z","caller":"traceutil/trace.go:171","msg":"trace[955285790] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"531.912699ms","start":"2026-05-08T02:29:29.128623Z","end":"2026-05-08T02:29:29.660536Z","steps":["trace[955285790] 'process raft request' (duration: 531.382484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:29.6609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:29.128592Z","time spent":"532.149051ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:36.169681Z","caller":"traceutil/trace.go:171","msg":"trace[1225716292] linearizableReadLoop","detail":"{readStateIndex:634; appliedIndex:633; }","duration":"479.939188ms","start":"2026-05-08T02:29:35.689664Z","end":"2026-05-08T02:29:36.169604Z","steps":["trace[1225716292] 'read index received' (duration: 479.39108ms)","trace[1225716292] 'applied index is now lower than readState.Index' (duration: 546.538µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:36.16977Z","caller":"traceutil/trace.go:171","msg":"trace[1867744387] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"497.537831ms","start":"2026-05-08T02:29:35.672155Z","end":"2026-05-08T02:29:36.169693Z","steps":["trace[1867744387] 'process raft request' (duration: 496.912322ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.170695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:35.672133Z","time spent":"498.383452ms","remote":"127.0.0.1:50590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:36.171009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.306146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T02:29:36.171093Z","caller":"traceutil/trace.go:171","msg":"trace[1922928490] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:616; }","duration":"481.460309ms","start":"2026-05-08T02:29:35.689611Z","end":"2026-05-08T02:29:36.171072Z","steps":["trace[1922928490] 'agreement among raft nodes before linearized reading' (duration: 480.224832ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.171142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:35.689593Z","time spent":"481.537319ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-08T02:29:36.670193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262226949418102617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:29:36.795471Z","caller":"traceutil/trace.go:171","msg":"trace[1498127724] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"905.993682ms","start":"2026-05-08T02:29:35.889449Z","end":"2026-05-08T02:29:36.795442Z","steps":["trace[1498127724] 'process raft request' (duration: 905.360383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.795681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:35.888935Z","time spent":"906.6171ms","remote":"127.0.0.1:50488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036197,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:36.841328Z","caller":"traceutil/trace.go:171","msg":"trace[1378366666] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:634; }","duration":"671.468966ms","start":"2026-05-08T02:29:36.169829Z","end":"2026-05-08T02:29:36.841298Z","steps":["trace[1378366666] 'read index received' (duration: 624.75709ms)","trace[1378366666] 'applied index is now lower than readState.Index' (duration: 46.710585ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:36.841458Z","caller":"traceutil/trace.go:171","msg":"trace[1126991848] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"663.036742ms","start":"2026-05-08T02:29:36.17841Z","end":"2026-05-08T02:29:36.841447Z","steps":["trace[1126991848] 'process raft request' (duration: 662.733137ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.841588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.178385Z","time spent":"663.104922ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:36.841817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.214277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T02:29:36.841835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.10793501s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:29:36.841896Z","caller":"traceutil/trace.go:171","msg":"trace[828917680] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:618; }","duration":"492.378369ms","start":"2026-05-08T02:29:36.349496Z","end":"2026-05-08T02:29:36.841875Z","steps":["trace[828917680] 'agreement among raft nodes before linearized reading' (duration: 492.129576ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.841955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.349468Z","time spent":"492.47901ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-08T02:29:36.841913Z","caller":"traceutil/trace.go:171","msg":"trace[1533136855] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.108047651s","start":"2026-05-08T02:29:35.733852Z","end":"2026-05-08T02:29:36.8419Z","steps":["trace[1533136855] 'agreement among raft nodes before linearized reading' (duration: 1.107853758s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.842041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.288779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:29:36.842136Z","caller":"traceutil/trace.go:171","msg":"trace[295581568] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"459.447862ms","start":"2026-05-08T02:29:36.382663Z","end":"2026-05-08T02:29:36.842111Z","steps":["trace[295581568] 'agreement among raft nodes before linearized reading' (duration: 459.049046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.842162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.329003ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T02:29:36.842192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.382651Z","time spent":"459.526572ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:29:36.842202Z","caller":"traceutil/trace.go:171","msg":"trace[698959250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"460.436975ms","start":"2026-05-08T02:29:36.381753Z","end":"2026-05-08T02:29:36.84219Z","steps":["trace[698959250] 'agreement among raft nodes before linearized reading' (duration: 460.253912ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:36.842219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:35.733832Z","time spent":"1.108374105s","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:29:36.842243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.381729Z","time spent":"460.506095ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:29:36.842804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.312335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.185\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-08T02:29:36.84285Z","caller":"traceutil/trace.go:171","msg":"trace[258281171] range","detail":"{range_begin:/registry/masterleases/199.19.213.185; range_end:; response_count:1; response_revision:618; }","duration":"247.417037ms","start":"2026-05-08T02:29:36.59542Z","end":"2026-05-08T02:29:36.842837Z","steps":["trace[258281171] 'agreement among raft nodes before linearized reading' (duration: 246.269191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:38.336363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.472358881s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"info","ts":"2026-05-08T02:29:38.33676Z","caller":"traceutil/trace.go:171","msg":"trace[797635684] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"1.471215126s","start":"2026-05-08T02:29:36.865524Z","end":"2026-05-08T02:29:38.336739Z","steps":["trace[797635684] 'process raft request' (duration: 1.471164686s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:38.336866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.865503Z","time spent":"1.471304167s","remote":"127.0.0.1:50722","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:29:38.337075Z","caller":"traceutil/trace.go:171","msg":"trace[1702043968] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.488563843s","start":"2026-05-08T02:29:36.848473Z","end":"2026-05-08T02:29:38.337037Z","steps":["trace[1702043968] 'process raft request' (duration: 1.488143347s)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:38.33717Z","caller":"traceutil/trace.go:171","msg":"trace[318413120] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"1.487614859s","start":"2026-05-08T02:29:36.849537Z","end":"2026-05-08T02:29:38.337152Z","steps":["trace[318413120] 'process raft request' (duration: 1.487116612s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:38.337284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.848459Z","time spent":"1.488709074s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:38.337283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.849528Z","time spent":"1.48768912s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:38.337354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.844054Z","time spent":"1.493290327s","remote":"127.0.0.1:50434","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-08T02:29:38.337412Z","caller":"traceutil/trace.go:171","msg":"trace[1942350068] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:636; }","duration":"1.492717359s","start":"2026-05-08T02:29:36.844658Z","end":"2026-05-08T02:29:38.337375Z","steps":["trace[1942350068] 'read index received' (duration: 19.382314ms)","trace[1942350068] 'applied index is now lower than readState.Index' (duration: 1.473332125s)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:38.337452Z","caller":"traceutil/trace.go:171","msg":"trace[1623134798] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.490180284s","start":"2026-05-08T02:29:36.847252Z","end":"2026-05-08T02:29:38.337432Z","steps":["trace[1623134798] 'process raft request' (duration: 1.489230471s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:38.337567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.492896282s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-08T02:29:38.337642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.84723Z","time spent":"1.490294535s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:38.337654Z","caller":"traceutil/trace.go:171","msg":"trace[1137284834] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:622; }","duration":"1.493006923s","start":"2026-05-08T02:29:36.844629Z","end":"2026-05-08T02:29:38.337636Z","steps":["trace[1137284834] 'agreement among raft nodes before linearized reading' (duration: 1.492886581s)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:38.337706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:36.844598Z","time spent":"1.493100384s","remote":"127.0.0.1:50414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-08T02:29:38.354607Z","caller":"traceutil/trace.go:171","msg":"trace[1209620973] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"178.807727ms","start":"2026-05-08T02:29:38.175771Z","end":"2026-05-08T02:29:38.354579Z","steps":["trace[1209620973] 'process raft request' (duration: 178.632145ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.460644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326845ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:29:40.460809Z","caller":"traceutil/trace.go:171","msg":"trace[1894018347] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:648; }","duration":"104.512738ms","start":"2026-05-08T02:29:40.356267Z","end":"2026-05-08T02:29:40.46078Z","steps":["trace[1894018347] 'range keys from in-memory index tree' (duration: 104.305555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.461769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.646827ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:29:40.461859Z","caller":"traceutil/trace.go:171","msg":"trace[1237432212] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"648.477327ms","start":"2026-05-08T02:29:39.813363Z","end":"2026-05-08T02:29:40.461841Z","steps":["trace[1237432212] 'process raft request' (duration: 514.393283ms)","trace[1237432212] 'compare' (duration: 132.848955ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:40.461941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:39.813353Z","time spent":"648.535737ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5153,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:40.466479Z","caller":"traceutil/trace.go:171","msg":"trace[1851150187] transaction","detail":"{read_only:false; number_of_response:0; response_revision:649; }","duration":"643.063068ms","start":"2026-05-08T02:29:39.823387Z","end":"2026-05-08T02:29:40.46645Z","steps":["trace[1851150187] 'process raft request' (duration: 642.929206ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.466675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:39.823356Z","time spent":"643.229361ms","remote":"127.0.0.1:50896","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:29:40.467128Z","caller":"traceutil/trace.go:171","msg":"trace[894536543] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"471.063546ms","start":"2026-05-08T02:29:39.996036Z","end":"2026-05-08T02:29:40.467099Z","steps":["trace[894536543] 'process raft request' (duration: 471.022905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.467238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:39.995996Z","time spent":"471.191017ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3353,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:29:40.467312Z","caller":"traceutil/trace.go:171","msg":"trace[1669728914] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"574.721542ms","start":"2026-05-08T02:29:39.892524Z","end":"2026-05-08T02:29:40.467245Z","steps":["trace[1669728914] 'process raft request' (duration: 574.067442ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:40.467368Z","caller":"traceutil/trace.go:171","msg":"trace[2061512132] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"574.444268ms","start":"2026-05-08T02:29:39.892889Z","end":"2026-05-08T02:29:40.467333Z","steps":["trace[2061512132] 'process raft request' (duration: 574.127314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.467651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:39.892882Z","time spent":"574.627701ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:29:40.468041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:39.892493Z","time spent":"574.933966ms","remote":"127.0.0.1:50488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:29:40.479174Z","caller":"traceutil/trace.go:171","msg":"trace[2007168488] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:669; }","duration":"136.147585ms","start":"2026-05-08T02:29:40.343Z","end":"2026-05-08T02:29:40.479147Z","steps":["trace[2007168488] 'read index received' (duration: 123.377332ms)","trace[2007168488] 'applied index is now lower than readState.Index' (duration: 12.769333ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:40.479326Z","caller":"traceutil/trace.go:171","msg":"trace[1004020933] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"280.742527ms","start":"2026-05-08T02:29:40.198478Z","end":"2026-05-08T02:29:40.47922Z","steps":["trace[1004020933] 'process raft request' (duration: 280.485493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.481453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.764304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:29:40.481535Z","caller":"traceutil/trace.go:171","msg":"trace[296969856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:654; }","duration":"138.563742ms","start":"2026-05-08T02:29:40.342954Z","end":"2026-05-08T02:29:40.481518Z","steps":["trace[296969856] 'agreement among raft nodes before linearized reading' (duration: 136.527441ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:40.483759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.681773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-08T02:29:40.483786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.850061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:29:40.48382Z","caller":"traceutil/trace.go:171","msg":"trace[349000090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:657; }","duration":"139.912761ms","start":"2026-05-08T02:29:40.343898Z","end":"2026-05-08T02:29:40.483811Z","steps":["trace[349000090] 'agreement among raft nodes before linearized reading' (duration: 139.768719ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:40.483846Z","caller":"traceutil/trace.go:171","msg":"trace[2028743007] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:657; }","duration":"140.800955ms","start":"2026-05-08T02:29:40.343Z","end":"2026-05-08T02:29:40.483801Z","steps":["trace[2028743007] 'agreement among raft nodes before linearized reading' (duration: 140.631542ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:43.30172Z","caller":"traceutil/trace.go:171","msg":"trace[2139064428] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"299.8555ms","start":"2026-05-08T02:29:43.001843Z","end":"2026-05-08T02:29:43.301699Z","steps":["trace[2139064428] 'process raft request' (duration: 299.695047ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:43.301991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:43.001809Z","time spent":"300.046173ms","remote":"127.0.0.1:50450","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-08T02:29:43.402622Z","caller":"traceutil/trace.go:171","msg":"trace[1313355731] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"389.448458ms","start":"2026-05-08T02:29:43.013139Z","end":"2026-05-08T02:29:43.402587Z","steps":["trace[1313355731] 'process raft request' (duration: 355.017872ms)","trace[1313355731] 'compare' (duration: 34.231253ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:29:43.402793Z","caller":"traceutil/trace.go:171","msg":"trace[338539136] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"209.156627ms","start":"2026-05-08T02:29:43.193522Z","end":"2026-05-08T02:29:43.402679Z","steps":["trace[338539136] 'read index received' (duration: 108.105026ms)","trace[338539136] 'applied index is now lower than readState.Index' (duration: 101.048731ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:29:43.402783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:43.013111Z","time spent":"389.5944ms","remote":"127.0.0.1:50478","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-08T02:29:43.403162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.629095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-05-08T02:29:43.403199Z","caller":"traceutil/trace.go:171","msg":"trace[330879798] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:706; }","duration":"209.686346ms","start":"2026-05-08T02:29:43.193504Z","end":"2026-05-08T02:29:43.403191Z","steps":["trace[330879798] 'agreement among raft nodes before linearized reading' (duration: 209.34652ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:29:53.639747Z","caller":"traceutil/trace.go:171","msg":"trace[1329764776] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"660.26682ms","start":"2026-05-08T02:29:52.979449Z","end":"2026-05-08T02:29:53.639716Z","steps":["trace[1329764776] 'process raft request' (duration: 660.079736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:29:53.639977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:29:52.979426Z","time spent":"660.425502ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:31:00.624676Z","caller":"traceutil/trace.go:171","msg":"trace[2115268493] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"301.411061ms","start":"2026-05-08T02:31:00.323233Z","end":"2026-05-08T02:31:00.624644Z","steps":["trace[2115268493] 'process raft request' (duration: 301.237745ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:00.624904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:00.323209Z","time spent":"301.574576ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:31:00.6412Z","caller":"traceutil/trace.go:171","msg":"trace[1640180783] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"181.348824ms","start":"2026-05-08T02:31:00.459806Z","end":"2026-05-08T02:31:00.641155Z","steps":["trace[1640180783] 'process raft request' (duration: 181.124437ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:01.047959Z","caller":"traceutil/trace.go:171","msg":"trace[1580051327] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"128.626041ms","start":"2026-05-08T02:31:00.919306Z","end":"2026-05-08T02:31:01.047932Z","steps":["trace[1580051327] 'process raft request' (duration: 128.487937ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:01.048214Z","caller":"traceutil/trace.go:171","msg":"trace[1256462795] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"128.749045ms","start":"2026-05-08T02:31:00.919424Z","end":"2026-05-08T02:31:01.048173Z","steps":["trace[1256462795] 'process raft request' (duration: 128.469766ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:03.181645Z","caller":"traceutil/trace.go:171","msg":"trace[2108891663] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"101.74098ms","start":"2026-05-08T02:31:03.079869Z","end":"2026-05-08T02:31:03.18161Z","steps":["trace[2108891663] 'process raft request' (duration: 38.636687ms)","trace[2108891663] 'compare' (duration: 62.822204ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:03.397462Z","caller":"traceutil/trace.go:171","msg":"trace[866017241] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"137.658912ms","start":"2026-05-08T02:31:03.259776Z","end":"2026-05-08T02:31:03.397435Z","steps":["trace[866017241] 'process raft request' (duration: 137.474056ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:16.355567Z","caller":"traceutil/trace.go:171","msg":"trace[1923078720] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"136.50022ms","start":"2026-05-08T02:31:16.219033Z","end":"2026-05-08T02:31:16.355533Z","steps":["trace[1923078720] 'process raft request' (duration: 136.297614ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:21.296096Z","caller":"traceutil/trace.go:171","msg":"trace[1698080936] linearizableReadLoop","detail":"{readStateIndex:1254; appliedIndex:1253; }","duration":"110.59322ms","start":"2026-05-08T02:31:21.185473Z","end":"2026-05-08T02:31:21.296066Z","steps":["trace[1698080936] 'read index received' (duration: 110.362863ms)","trace[1698080936] 'applied index is now lower than readState.Index' (duration: 229.207µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:21.296162Z","caller":"traceutil/trace.go:171","msg":"trace[772911290] transaction","detail":"{read_only:false; response_revision:1211; number_of_response:1; }","duration":"145.214072ms","start":"2026-05-08T02:31:21.150909Z","end":"2026-05-08T02:31:21.296123Z","steps":["trace[772911290] 'process raft request' (duration: 144.996595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:21.296322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.552919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T02:31:21.296322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.836989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T02:31:21.29635Z","caller":"traceutil/trace.go:171","msg":"trace[1854183132] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1211; }","duration":"104.646252ms","start":"2026-05-08T02:31:21.191696Z","end":"2026-05-08T02:31:21.296342Z","steps":["trace[1854183132] 'agreement among raft nodes before linearized reading' (duration: 104.507788ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:21.296374Z","caller":"traceutil/trace.go:171","msg":"trace[1105547721] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1211; }","duration":"110.936722ms","start":"2026-05-08T02:31:21.185423Z","end":"2026-05-08T02:31:21.29636Z","steps":["trace[1105547721] 'agreement among raft nodes before linearized reading' (duration: 110.749216ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:22.013407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"670.253687ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:31:22.013565Z","caller":"traceutil/trace.go:171","msg":"trace[918684364] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"712.504093ms","start":"2026-05-08T02:31:21.301033Z","end":"2026-05-08T02:31:22.013537Z","steps":["trace[918684364] 'process raft request' (duration: 41.985487ms)","trace[918684364] 'compare' (duration: 669.593565ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:22.013664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.301007Z","time spent":"712.606086ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:31:22.460075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.999856ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-08T02:31:22.460459Z","caller":"traceutil/trace.go:171","msg":"trace[1220078505] linearizableReadLoop","detail":"{readStateIndex:1256; appliedIndex:1254; }","duration":"1.139757723s","start":"2026-05-08T02:31:21.320662Z","end":"2026-05-08T02:31:22.460419Z","steps":["trace[1220078505] 'read index received' (duration: 22.469628ms)","trace[1220078505] 'applied index is now lower than readState.Index' (duration: 1.117285115s)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:22.460503Z","caller":"traceutil/trace.go:171","msg":"trace[577983587] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"972.073843ms","start":"2026-05-08T02:31:21.488393Z","end":"2026-05-08T02:31:22.460467Z","steps":["trace[577983587] 'process raft request' (duration: 971.828655ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:22.460681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.488362Z","time spent":"972.220988ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-08T02:31:22.460714Z","caller":"traceutil/trace.go:171","msg":"trace[958420351] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"1.159123767s","start":"2026-05-08T02:31:21.301474Z","end":"2026-05-08T02:31:22.460598Z","steps":["trace[958420351] 'process raft request' (duration: 834.441329ms)","trace[958420351] 'compare' (duration: 323.796519ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:22.461673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.301461Z","time spent":"1.159292584s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:31:22.583637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.308802ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-08T02:31:22.583761Z","caller":"traceutil/trace.go:171","msg":"trace[1410719439] linearizableReadLoop","detail":"{readStateIndex:1258; appliedIndex:1257; }","duration":"123.204039ms","start":"2026-05-08T02:31:22.460534Z","end":"2026-05-08T02:31:22.583738Z","steps":["trace[1410719439] 'read index received' (duration: 199.997µs)","trace[1410719439] 'applied index is now lower than readState.Index' (duration: 123.002271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:22.584038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263324664s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-08T02:31:22.584256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.624769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:5 size:1050260"} {"level":"info","ts":"2026-05-08T02:31:22.58426Z","caller":"traceutil/trace.go:171","msg":"trace[1591675559] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1214; }","duration":"1.263614024s","start":"2026-05-08T02:31:21.320614Z","end":"2026-05-08T02:31:22.584228Z","steps":["trace[1591675559] 'agreement among raft nodes before linearized reading' (duration: 1.140131706s)","trace[1591675559] 'range keys from in-memory index tree' (duration: 123.027642ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:22.584302Z","caller":"traceutil/trace.go:171","msg":"trace[1036471106] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:5; response_revision:1214; }","duration":"926.726382ms","start":"2026-05-08T02:31:21.657563Z","end":"2026-05-08T02:31:22.584289Z","steps":["trace[1036471106] 'agreement among raft nodes before linearized reading' (duration: 926.236446ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:22.584326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.320595Z","time spent":"1.263716777s","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-08T02:31:22.584349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.65754Z","time spent":"926.799375ms","remote":"127.0.0.1:50488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":5,"response size":1050282,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"warn","ts":"2026-05-08T02:31:22.584376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.711365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-08T02:31:22.58461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.802591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:31:22.584598Z","caller":"traceutil/trace.go:171","msg":"trace[696345362] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1214; }","duration":"171.978934ms","start":"2026-05-08T02:31:22.412528Z","end":"2026-05-08T02:31:22.584507Z","steps":["trace[696345362] 'agreement among raft nodes before linearized reading' (duration: 171.472317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:22.584789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.956767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:31:22.584887Z","caller":"traceutil/trace.go:171","msg":"trace[1521820555] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1214; }","duration":"850.140723ms","start":"2026-05-08T02:31:21.734727Z","end":"2026-05-08T02:31:22.584868Z","steps":["trace[1521820555] 'agreement among raft nodes before linearized reading' (duration: 849.922386ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:22.584668Z","caller":"traceutil/trace.go:171","msg":"trace[1356612263] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:1214; }","duration":"500.908225ms","start":"2026-05-08T02:31:22.083743Z","end":"2026-05-08T02:31:22.584651Z","steps":["trace[1356612263] 'agreement among raft nodes before linearized reading' (duration: 500.75765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:22.584945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:21.734697Z","time spent":"850.235696ms","remote":"127.0.0.1:50410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-08T02:31:22.585069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:22.083718Z","time spent":"501.329919ms","remote":"127.0.0.1:55746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"info","ts":"2026-05-08T02:31:22.718576Z","caller":"traceutil/trace.go:171","msg":"trace[208119379] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"129.156458ms","start":"2026-05-08T02:31:22.589394Z","end":"2026-05-08T02:31:22.718551Z","steps":["trace[208119379] 'process raft request' (duration: 127.245824ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:24.596132Z","caller":"traceutil/trace.go:171","msg":"trace[1602272594] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"177.024679ms","start":"2026-05-08T02:31:24.419089Z","end":"2026-05-08T02:31:24.596113Z","steps":["trace[1602272594] 'read index received' (duration: 176.962897ms)","trace[1602272594] 'applied index is now lower than readState.Index' (duration: 60.942µs)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:24.596287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.180605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:31:24.596313Z","caller":"traceutil/trace.go:171","msg":"trace[1068658247] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1219; }","duration":"177.274047ms","start":"2026-05-08T02:31:24.419033Z","end":"2026-05-08T02:31:24.596307Z","steps":["trace[1068658247] 'agreement among raft nodes before linearized reading' (duration: 177.180424ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:24.596452Z","caller":"traceutil/trace.go:171","msg":"trace[1119910345] transaction","detail":"{read_only:false; response_revision:1219; number_of_response:1; }","duration":"573.131467ms","start":"2026-05-08T02:31:24.023096Z","end":"2026-05-08T02:31:24.596228Z","steps":["trace[1119910345] 'process raft request' (duration: 572.889799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:24.596756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:24.023067Z","time spent":"573.488579ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:31:24.598946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.84406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T02:31:24.599028Z","caller":"traceutil/trace.go:171","msg":"trace[1322504031] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1220; }","duration":"132.977104ms","start":"2026-05-08T02:31:24.466031Z","end":"2026-05-08T02:31:24.599008Z","steps":["trace[1322504031] 'agreement among raft nodes before linearized reading' (duration: 132.748896ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:24.59934Z","caller":"traceutil/trace.go:171","msg":"trace[294213723] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"133.222642ms","start":"2026-05-08T02:31:24.466101Z","end":"2026-05-08T02:31:24.599324Z","steps":["trace[294213723] 'process raft request' (duration: 132.56162ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:24.912766Z","caller":"traceutil/trace.go:171","msg":"trace[946849311] linearizableReadLoop","detail":"{readStateIndex:1266; appliedIndex:1265; }","duration":"189.207406ms","start":"2026-05-08T02:31:24.723522Z","end":"2026-05-08T02:31:24.91273Z","steps":["trace[946849311] 'read index received' (duration: 91.042238ms)","trace[946849311] 'applied index is now lower than readState.Index' (duration: 98.163618ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:24.91284Z","caller":"traceutil/trace.go:171","msg":"trace[1405085605] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"245.666887ms","start":"2026-05-08T02:31:24.667125Z","end":"2026-05-08T02:31:24.912792Z","steps":["trace[1405085605] 'process raft request' (duration: 147.453597ms)","trace[1405085605] 'compare' (duration: 98.005693ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:24.913107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.559188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:31:24.913151Z","caller":"traceutil/trace.go:171","msg":"trace[881068774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"189.687393ms","start":"2026-05-08T02:31:24.723452Z","end":"2026-05-08T02:31:24.91314Z","steps":["trace[881068774] 'agreement among raft nodes before linearized reading' (duration: 189.472526ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:38.333559Z","caller":"traceutil/trace.go:171","msg":"trace[1831216481] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"143.187931ms","start":"2026-05-08T02:31:38.190309Z","end":"2026-05-08T02:31:38.333497Z","steps":["trace[1831216481] 'process raft request' (duration: 142.934593ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:38.843837Z","caller":"traceutil/trace.go:171","msg":"trace[514183575] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1331; }","duration":"110.900792ms","start":"2026-05-08T02:31:38.732911Z","end":"2026-05-08T02:31:38.843812Z","steps":["trace[514183575] 'read index received' (duration: 93.803494ms)","trace[514183575] 'applied index is now lower than readState.Index' (duration: 17.096218ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:38.843947Z","caller":"traceutil/trace.go:171","msg":"trace[305873761] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"147.291733ms","start":"2026-05-08T02:31:38.696613Z","end":"2026-05-08T02:31:38.843905Z","steps":["trace[305873761] 'process raft request' (duration: 130.173765ms)","trace[305873761] 'compare' (duration: 16.910691ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:38.844027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.067688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:31:38.844201Z","caller":"traceutil/trace.go:171","msg":"trace[531019753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1284; }","duration":"111.327596ms","start":"2026-05-08T02:31:38.732855Z","end":"2026-05-08T02:31:38.844183Z","steps":["trace[531019753] 'agreement among raft nodes before linearized reading' (duration: 111.056587ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:42.827696Z","caller":"traceutil/trace.go:171","msg":"trace[1729960941] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1355; }","duration":"129.153457ms","start":"2026-05-08T02:31:42.698522Z","end":"2026-05-08T02:31:42.827675Z","steps":["trace[1729960941] 'read index received' (duration: 129.021233ms)","trace[1729960941] 'applied index is now lower than readState.Index' (duration: 131.214µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:42.827748Z","caller":"traceutil/trace.go:171","msg":"trace[924313164] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"151.329915ms","start":"2026-05-08T02:31:42.676383Z","end":"2026-05-08T02:31:42.827712Z","steps":["trace[924313164] 'process raft request' (duration: 151.117597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:42.828113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.575772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-08T02:31:42.828363Z","caller":"traceutil/trace.go:171","msg":"trace[1844989288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"129.869773ms","start":"2026-05-08T02:31:42.698478Z","end":"2026-05-08T02:31:42.828348Z","steps":["trace[1844989288] 'agreement among raft nodes before linearized reading' (duration: 129.285462ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:45.321913Z","caller":"traceutil/trace.go:171","msg":"trace[2098164010] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1368; }","duration":"269.216064ms","start":"2026-05-08T02:31:45.052672Z","end":"2026-05-08T02:31:45.321888Z","steps":["trace[2098164010] 'read index received' (duration: 50.130972ms)","trace[2098164010] 'applied index is now lower than readState.Index' (duration: 219.083992ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:45.322064Z","caller":"traceutil/trace.go:171","msg":"trace[229915905] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"271.283825ms","start":"2026-05-08T02:31:45.050726Z","end":"2026-05-08T02:31:45.32201Z","steps":["trace[229915905] 'process raft request' (duration: 270.952274ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:45.322368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.668169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T02:31:45.322449Z","caller":"traceutil/trace.go:171","msg":"trace[1298566527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1321; }","duration":"269.821085ms","start":"2026-05-08T02:31:45.052615Z","end":"2026-05-08T02:31:45.322436Z","steps":["trace[1298566527] 'agreement among raft nodes before linearized reading' (duration: 269.445762ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:45.322508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.940676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2476"} {"level":"info","ts":"2026-05-08T02:31:45.32259Z","caller":"traceutil/trace.go:171","msg":"trace[374187885] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1321; }","duration":"223.100632ms","start":"2026-05-08T02:31:45.099468Z","end":"2026-05-08T02:31:45.322569Z","steps":["trace[374187885] 'agreement among raft nodes before linearized reading' (duration: 222.785751ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:47.482609Z","caller":"traceutil/trace.go:171","msg":"trace[1217691836] linearizableReadLoop","detail":"{readStateIndex:1378; appliedIndex:1377; }","duration":"117.992691ms","start":"2026-05-08T02:31:47.364589Z","end":"2026-05-08T02:31:47.482582Z","steps":["trace[1217691836] 'read index received' (duration: 117.862577ms)","trace[1217691836] 'applied index is now lower than readState.Index' (duration: 128.734µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:47.482683Z","caller":"traceutil/trace.go:171","msg":"trace[506888362] transaction","detail":"{read_only:false; response_revision:1328; number_of_response:1; }","duration":"151.105356ms","start":"2026-05-08T02:31:47.331543Z","end":"2026-05-08T02:31:47.482648Z","steps":["trace[506888362] 'process raft request' (duration: 150.839636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:47.482857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.24182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-08T02:31:47.482894Z","caller":"traceutil/trace.go:171","msg":"trace[452688390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1328; }","duration":"118.323533ms","start":"2026-05-08T02:31:47.364559Z","end":"2026-05-08T02:31:47.482883Z","steps":["trace[452688390] 'agreement among raft nodes before linearized reading' (duration: 118.135056ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:51.548498Z","caller":"traceutil/trace.go:171","msg":"trace[234938366] transaction","detail":"{read_only:false; response_revision:1339; number_of_response:1; }","duration":"181.970195ms","start":"2026-05-08T02:31:51.366502Z","end":"2026-05-08T02:31:51.548472Z","steps":["trace[234938366] 'process raft request' (duration: 181.751657ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:51.669825Z","caller":"traceutil/trace.go:171","msg":"trace[194783985] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"115.694303ms","start":"2026-05-08T02:31:51.554106Z","end":"2026-05-08T02:31:51.6698Z","steps":["trace[194783985] 'process raft request' (duration: 67.93932ms)","trace[194783985] 'compare' (duration: 47.459713ms)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:51.73191Z","caller":"traceutil/trace.go:171","msg":"trace[898911260] linearizableReadLoop","detail":"{readStateIndex:1391; appliedIndex:1389; }","duration":"123.946272ms","start":"2026-05-08T02:31:51.607931Z","end":"2026-05-08T02:31:51.731877Z","steps":["trace[898911260] 'read index received' (duration: 14.143345ms)","trace[898911260] 'applied index is now lower than readState.Index' (duration: 109.801397ms)"],"step_count":2} {"level":"warn","ts":"2026-05-08T02:31:51.732172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.207421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-08T02:31:51.732447Z","caller":"traceutil/trace.go:171","msg":"trace[148853585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1341; }","duration":"124.549003ms","start":"2026-05-08T02:31:51.607882Z","end":"2026-05-08T02:31:51.732431Z","steps":["trace[148853585] 'agreement among raft nodes before linearized reading' (duration: 124.105908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.608227Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":262226949418104346,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-08T02:31:57.781274Z","caller":"traceutil/trace.go:171","msg":"trace[685645906] linearizableReadLoop","detail":"{readStateIndex:1415; appliedIndex:1414; }","duration":"673.149988ms","start":"2026-05-08T02:31:57.108093Z","end":"2026-05-08T02:31:57.781243Z","steps":["trace[685645906] 'read index received' (duration: 672.847198ms)","trace[685645906] 'applied index is now lower than readState.Index' (duration: 301.27µs)"],"step_count":2} {"level":"info","ts":"2026-05-08T02:31:57.781634Z","caller":"traceutil/trace.go:171","msg":"trace[453725452] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"736.864006ms","start":"2026-05-08T02:31:57.044743Z","end":"2026-05-08T02:31:57.781607Z","steps":["trace[453725452] 'process raft request' (duration: 736.307687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.78189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:57.044705Z","time spent":"736.994841ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-08T02:31:57.782016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.270656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-08T02:31:57.782109Z","caller":"traceutil/trace.go:171","msg":"trace[1684235653] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1363; }","duration":"209.440572ms","start":"2026-05-08T02:31:57.572647Z","end":"2026-05-08T02:31:57.782088Z","steps":["trace[1684235653] 'agreement among raft nodes before linearized reading' (duration: 209.059999ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.782066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.966492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-08T02:31:57.781894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.883254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-08T02:31:57.782239Z","caller":"traceutil/trace.go:171","msg":"trace[839427159] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1363; }","duration":"605.19389ms","start":"2026-05-08T02:31:57.177024Z","end":"2026-05-08T02:31:57.782218Z","steps":["trace[839427159] 'agreement among raft nodes before linearized reading' (duration: 604.610249ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:57.782381Z","caller":"traceutil/trace.go:171","msg":"trace[205319929] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1363; }","duration":"198.409913ms","start":"2026-05-08T02:31:57.583906Z","end":"2026-05-08T02:31:57.782316Z","steps":["trace[205319929] 'agreement among raft nodes before linearized reading' (duration: 197.74248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.78246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.297569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-08T02:31:57.782608Z","caller":"traceutil/trace.go:171","msg":"trace[1485702662] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1363; }","duration":"674.583979ms","start":"2026-05-08T02:31:57.108004Z","end":"2026-05-08T02:31:57.782588Z","steps":["trace[1485702662] 'agreement among raft nodes before linearized reading' (duration: 673.48892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.782658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:57.107965Z","time spent":"674.679032ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-08T02:31:57.782338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.966706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-08T02:31:57.782786Z","caller":"traceutil/trace.go:171","msg":"trace[1969620970] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1363; }","duration":"551.422092ms","start":"2026-05-08T02:31:57.231307Z","end":"2026-05-08T02:31:57.782729Z","steps":["trace[1969620970] 'agreement among raft nodes before linearized reading' (duration: 550.81034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-08T02:31:57.782863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:57.231221Z","time spent":"551.619419ms","remote":"127.0.0.1:50542","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-08T02:31:57.783423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-08T02:31:57.176989Z","time spent":"605.343545ms","remote":"127.0.0.1:50644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-08T02:31:57.908391Z","caller":"traceutil/trace.go:171","msg":"trace[2050447977] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"121.080364ms","start":"2026-05-08T02:31:57.787277Z","end":"2026-05-08T02:31:57.908357Z","steps":["trace[2050447977] 'process raft request' (duration: 120.883707ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:57.908542Z","caller":"traceutil/trace.go:171","msg":"trace[716647362] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"120.985391ms","start":"2026-05-08T02:31:57.787506Z","end":"2026-05-08T02:31:57.908491Z","steps":["trace[716647362] 'process raft request' (duration: 120.772483ms)"],"step_count":1} {"level":"info","ts":"2026-05-08T02:31:57.908617Z","caller":"traceutil/trace.go:171","msg":"trace[1409437158] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"121.016412ms","start":"2026-05-08T02:31:57.787555Z","end":"2026-05-08T02:31:57.908571Z","steps":["trace[1409437158] 'process raft request' (duration: 120.788553ms)"],"step_count":1}