{"level":"warn","ts":"2026-07-09T16:35:59.891261Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:35:59.891443Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.233: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.233:2380","--initial-cluster=instance=https://199.19.213.233:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.233:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.233: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-07-09T16:35:59.891539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T16:35:59.891554Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-07-09T16:35:59.891577Z","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-07-09T16:35:59.89238Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"]} {"level":"info","ts":"2026-07-09T16:35:59.892508Z","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.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.233: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-07-09T16:35:59.897726Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.905328ms"} {"level":"info","ts":"2026-07-09T16:35:59.908116Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"de5c2ed1f829ccc5","cluster-id":"1c2360c7fdb72bdb"} {"level":"info","ts":"2026-07-09T16:35:59.908368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:35:59.908442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 0"} {"level":"info","ts":"2026-07-09T16:35:59.908483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft de5c2ed1f829ccc5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:35:59.908512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became follower at term 1"} {"level":"info","ts":"2026-07-09T16:35:59.908612Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"warn","ts":"2026-07-09T16:35:59.917702Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:35:59.920537Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:35:59.921998Z","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-07-09T16:35:59.925047Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"de5c2ed1f829ccc5","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:35:59.925192Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"de5c2ed1f829ccc5","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:35:59.925816Z","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-07-09T16:35:59.925893Z","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-07-09T16:35:59.925913Z","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-07-09T16:35:59.926209Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 switched to configuration voters=(16022733053623913669)"} {"level":"info","ts":"2026-07-09T16:35:59.926312Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","added-peer-id":"de5c2ed1f829ccc5","added-peer-peer-urls":["https://199.19.213.233:2380"]} {"level":"info","ts":"2026-07-09T16:35:59.930383Z","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-07-09T16:35:59.93074Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"de5c2ed1f829ccc5","initial-advertise-peer-urls":["https://199.19.213.233:2380"],"listen-peer-urls":["https://199.19.213.233:2380"],"advertise-client-urls":["https://199.19.213.233:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.233:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:35:59.930837Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:35:59.931015Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-07-09T16:35:59.931078Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.233:2380"} {"level":"info","ts":"2026-07-09T16:36:00.709866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:36:00.709927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:36:00.709987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgPreVoteResp from de5c2ed1f829ccc5 at term 1"} {"level":"info","ts":"2026-07-09T16:36:00.710062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:36:00.710089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 received MsgVoteResp from de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-07-09T16:36:00.710168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"de5c2ed1f829ccc5 became leader at term 2"} {"level":"info","ts":"2026-07-09T16:36:00.710196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: de5c2ed1f829ccc5 elected leader de5c2ed1f829ccc5 at term 2"} {"level":"info","ts":"2026-07-09T16:36:00.711445Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"de5c2ed1f829ccc5","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.233:2379]}","request-path":"/0/members/de5c2ed1f829ccc5/attributes","cluster-id":"1c2360c7fdb72bdb","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:36:00.711487Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:36:00.711492Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:00.711606Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:36:00.711806Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:36:00.711833Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:36:00.712324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"1c2360c7fdb72bdb","local-member-id":"de5c2ed1f829ccc5","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:00.712405Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:00.712427Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:36:00.713292Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.233:2379"} {"level":"info","ts":"2026-07-09T16:36:00.715448Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-09T16:36:03.515373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.785439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T16:36:03.51591Z","caller":"traceutil/trace.go:171","msg":"trace[886831192] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"471.890487ms","start":"2026-07-09T16:36:03.043959Z","end":"2026-07-09T16:36:03.51585Z","steps":["trace[886831192] 'read index received' (duration: 117.891524ms)","trace[886831192] 'applied index is now lower than readState.Index' (duration: 353.996823ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:03.516111Z","caller":"traceutil/trace.go:171","msg":"trace[517339374] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"473.137512ms","start":"2026-07-09T16:36:03.042909Z","end":"2026-07-09T16:36:03.516047Z","steps":["trace[517339374] 'process raft request' (duration: 119.042957ms)","trace[517339374] 'compare' (duration: 352.641295ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:03.51626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.260427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:03.516321Z","caller":"traceutil/trace.go:171","msg":"trace[757711482] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:72; }","duration":"472.388191ms","start":"2026-07-09T16:36:03.043898Z","end":"2026-07-09T16:36:03.516286Z","steps":["trace[757711482] 'agreement among raft nodes before linearized reading' (duration: 472.185875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:03.516365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:03.04388Z","time spent":"472.465463ms","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/edit\" "} {"level":"warn","ts":"2026-07-09T16:36:03.516359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:03.042897Z","time spent":"473.320568ms","remote":"127.0.0.1:47780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:36:03.516633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.757883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:03.516664Z","caller":"traceutil/trace.go:171","msg":"trace[1991144771] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"399.810644ms","start":"2026-07-09T16:36:03.116847Z","end":"2026-07-09T16:36:03.516658Z","steps":["trace[1991144771] 'agreement among raft nodes before linearized reading' (duration: 399.764703ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:03.516695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:03.116834Z","time spent":"399.855295ms","remote":"127.0.0.1:47404","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-07-09T16:36:04.540974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374884602324451,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:36:04.627467Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.096430376s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:36:06.040457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000094358s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:36:06.040586Z","caller":"traceutil/trace.go:171","msg":"trace[1946794049] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000276303s","start":"2026-07-09T16:36:04.040284Z","end":"2026-07-09T16:36:06.04056Z","steps":["trace[1946794049] 'agreement among raft nodes before linearized reading' (duration: 2.000123679s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:06.040672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:04.040262Z","time spent":"2.000395777s","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:36:06.216991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.99985329s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:36:06.217075Z","caller":"traceutil/trace.go:171","msg":"trace[580216257] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999991244s","start":"2026-07-09T16:36:04.217064Z","end":"2026-07-09T16:36:06.217055Z","steps":["trace[580216257] 'agreement among raft nodes before linearized reading' (duration: 1.999883381s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:06.217122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:04.217045Z","time spent":"2.000067985s","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:36:06.751194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.123367954s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-09T16:36:06.751305Z","caller":"traceutil/trace.go:171","msg":"trace[157412077] linearizableReadLoop","detail":"{readStateIndex:77; appliedIndex:76; }","duration":"2.710948254s","start":"2026-07-09T16:36:04.040335Z","end":"2026-07-09T16:36:06.751284Z","steps":["trace[157412077] 'read index received' (duration: 587.337663ms)","trace[157412077] 'applied index is now lower than readState.Index' (duration: 2.12360858s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:06.751521Z","caller":"traceutil/trace.go:171","msg":"trace[1615888599] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"3.220672214s","start":"2026-07-09T16:36:03.530821Z","end":"2026-07-09T16:36:06.751493Z","steps":["trace[1615888599] 'process raft request' (duration: 1.09691451s)","trace[1615888599] 'compare' (duration: 2.122320794s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:06.751726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:03.530807Z","time spent":"3.220879751s","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:36:06.752381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.944062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:06.752442Z","caller":"traceutil/trace.go:171","msg":"trace[1662390071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"435.038125ms","start":"2026-07-09T16:36:06.317386Z","end":"2026-07-09T16:36:06.752424Z","steps":["trace[1662390071] 'agreement among raft nodes before linearized reading' (duration: 434.913631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:06.752469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:06.317368Z","time spent":"435.095327ms","remote":"127.0.0.1:47412","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-07-09T16:36:06.752671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.29205709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:06.752697Z","caller":"traceutil/trace.go:171","msg":"trace[1497902775] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:73; }","duration":"2.292121002s","start":"2026-07-09T16:36:04.460569Z","end":"2026-07-09T16:36:06.75269Z","steps":["trace[1497902775] 'agreement among raft nodes before linearized reading' (duration: 2.292083641s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:06.752721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:04.46055Z","time spent":"2.292166664s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:36:06.773781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.400519607s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:06.773843Z","caller":"traceutil/trace.go:171","msg":"trace[346997501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:73; }","duration":"2.40062865s","start":"2026-07-09T16:36:04.373196Z","end":"2026-07-09T16:36:06.773825Z","steps":["trace[346997501] 'agreement among raft nodes before linearized reading' (duration: 2.378720888s)","trace[346997501] 'range keys from in-memory index tree' (duration: 21.798738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:06.773875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:04.373181Z","time spent":"2.400687941s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-09T16:36:07.919688Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374884602324456,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:36:08.192828Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.417254445s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:36:08.196404Z","caller":"traceutil/trace.go:171","msg":"trace[163064837] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"1.420857377s","start":"2026-07-09T16:36:06.775518Z","end":"2026-07-09T16:36:08.196376Z","steps":["trace[163064837] 'process raft request' (duration: 1.417666027s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:08.196545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:06.775509Z","time spent":"1.420973s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:36:08.201907Z","caller":"traceutil/trace.go:171","msg":"trace[1777511682] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:77; }","duration":"783.190826ms","start":"2026-07-09T16:36:07.418696Z","end":"2026-07-09T16:36:08.201886Z","steps":["trace[1777511682] 'read index received' (duration: 774.582931ms)","trace[1777511682] 'applied index is now lower than readState.Index' (duration: 8.606825ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:08.202088Z","caller":"traceutil/trace.go:171","msg":"trace[1131463493] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"1.426399124s","start":"2026-07-09T16:36:06.775679Z","end":"2026-07-09T16:36:08.202078Z","steps":["trace[1131463493] 'process raft request' (duration: 1.425942962s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:08.202176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:06.775653Z","time spent":"1.426463937s","remote":"127.0.0.1:47764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:36:08.202421Z","caller":"traceutil/trace.go:171","msg":"trace[802293173] transaction","detail":"{read_only:false; response_revision:76; number_of_response:1; }","duration":"1.426151798s","start":"2026-07-09T16:36:06.776249Z","end":"2026-07-09T16:36:08.202401Z","steps":["trace[802293173] 'process raft request' (duration: 1.425466678s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:08.202482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:06.776236Z","time spent":"1.426221489s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:36:08.202678Z","caller":"traceutil/trace.go:171","msg":"trace[753985093] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"1.269912998s","start":"2026-07-09T16:36:06.932755Z","end":"2026-07-09T16:36:08.202668Z","steps":["trace[753985093] 'process raft request' (duration: 1.269002202s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:08.202716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:07.24472Z","time spent":"957.99428ms","remote":"127.0.0.1:47500","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-09T16:36:08.202731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:06.93273Z","time spent":"1.26997906s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5882,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:36:08.202805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.101712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:36:08.202852Z","caller":"traceutil/trace.go:171","msg":"trace[36801514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"784.176944ms","start":"2026-07-09T16:36:07.418663Z","end":"2026-07-09T16:36:08.20284Z","steps":["trace[36801514] 'agreement among raft nodes before linearized reading' (duration: 784.099992ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:08.202873Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:07.418645Z","time spent":"784.223265ms","remote":"127.0.0.1:47412","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-07-09T16:36:10.389565Z","caller":"traceutil/trace.go:171","msg":"trace[1627966289] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"154.793359ms","start":"2026-07-09T16:36:10.234741Z","end":"2026-07-09T16:36:10.389534Z","steps":["trace[1627966289] 'process raft request' (duration: 100.512659ms)","trace[1627966289] 'compare' (duration: 54.106575ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:10.6104Z","caller":"traceutil/trace.go:171","msg":"trace[1622486962] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"173.745612ms","start":"2026-07-09T16:36:10.436629Z","end":"2026-07-09T16:36:10.610374Z","steps":["trace[1622486962] 'process raft request' (duration: 125.471713ms)","trace[1622486962] 'compare' (duration: 48.163156ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:14.474427Z","caller":"traceutil/trace.go:171","msg":"trace[902937859] linearizableReadLoop","detail":"{readStateIndex:346; appliedIndex:345; }","duration":"323.345202ms","start":"2026-07-09T16:36:14.151057Z","end":"2026-07-09T16:36:14.474402Z","steps":["trace[902937859] 'read index received' (duration: 280.343817ms)","trace[902937859] 'applied index is now lower than readState.Index' (duration: 43.000215ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:14.47466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.542508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"warn","ts":"2026-07-09T16:36:14.474834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.291493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:36:14.474838Z","caller":"traceutil/trace.go:171","msg":"trace[703805597] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:335; }","duration":"323.792974ms","start":"2026-07-09T16:36:14.151028Z","end":"2026-07-09T16:36:14.474821Z","steps":["trace[703805597] 'agreement among raft nodes before linearized reading' (duration: 323.496596ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:36:14.474864Z","caller":"traceutil/trace.go:171","msg":"trace[184130537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:335; }","duration":"226.344995ms","start":"2026-07-09T16:36:14.248508Z","end":"2026-07-09T16:36:14.474853Z","steps":["trace[184130537] 'agreement among raft nodes before linearized reading' (duration: 226.300454ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:14.474924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:14.151015Z","time spent":"323.862187ms","remote":"127.0.0.1:47626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-07-09T16:36:14.475066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.358055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:36:14.475248Z","caller":"traceutil/trace.go:171","msg":"trace[876907281] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:335; }","duration":"226.628013ms","start":"2026-07-09T16:36:14.248594Z","end":"2026-07-09T16:36:14.475222Z","steps":["trace[876907281] 'agreement among raft nodes before linearized reading' (duration: 226.348635ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:36:14.474638Z","caller":"traceutil/trace.go:171","msg":"trace[1947314948] transaction","detail":"{read_only:false; response_revision:335; number_of_response:1; }","duration":"376.929953ms","start":"2026-07-09T16:36:14.097641Z","end":"2026-07-09T16:36:14.474571Z","steps":["trace[1947314948] 'process raft request' (duration: 333.823135ms)","trace[1947314948] 'compare' (duration: 42.838171ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:14.475662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:36:14.09763Z","time spent":"377.740386ms","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:36:14.687135Z","caller":"traceutil/trace.go:171","msg":"trace[20966767] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:347; }","duration":"156.824417ms","start":"2026-07-09T16:36:14.530268Z","end":"2026-07-09T16:36:14.687093Z","steps":["trace[20966767] 'read index received' (duration: 74.421658ms)","trace[20966767] 'applied index is now lower than readState.Index' (duration: 82.401969ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:14.68724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.956881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:36:14.687248Z","caller":"traceutil/trace.go:171","msg":"trace[134774921] transaction","detail":"{read_only:false; response_revision:337; number_of_response:1; }","duration":"207.058181ms","start":"2026-07-09T16:36:14.480163Z","end":"2026-07-09T16:36:14.687221Z","steps":["trace[134774921] 'process raft request' (duration: 124.482277ms)","trace[134774921] 'compare' (duration: 82.341707ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:36:14.687292Z","caller":"traceutil/trace.go:171","msg":"trace[1521806772] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:0; response_revision:337; }","duration":"157.027543ms","start":"2026-07-09T16:36:14.530228Z","end":"2026-07-09T16:36:14.687255Z","steps":["trace[1521806772] 'agreement among raft nodes before linearized reading' (duration: 156.963571ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:36:22.672644Z","caller":"traceutil/trace.go:171","msg":"trace[497837861] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"107.712442ms","start":"2026-07-09T16:36:22.564905Z","end":"2026-07-09T16:36:22.672618Z","steps":["trace[497837861] 'process raft request' (duration: 107.599929ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:36:24.802105Z","caller":"traceutil/trace.go:171","msg":"trace[1457025474] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"119.596587ms","start":"2026-07-09T16:36:24.68248Z","end":"2026-07-09T16:36:24.802077Z","steps":["trace[1457025474] 'process raft request' (duration: 119.39222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:36:24.966819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.803158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:36:24.966918Z","caller":"traceutil/trace.go:171","msg":"trace[366761835] transaction","detail":"{read_only:false; response_revision:420; number_of_response:1; }","duration":"160.158ms","start":"2026-07-09T16:36:24.806741Z","end":"2026-07-09T16:36:24.966899Z","steps":["trace[366761835] 'process raft request' (duration: 47.210027ms)","trace[366761835] 'compare' (duration: 112.701446ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:36:25.253226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.816561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:36:25.253309Z","caller":"traceutil/trace.go:171","msg":"trace[1407727079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:421; }","duration":"103.945535ms","start":"2026-07-09T16:36:25.149345Z","end":"2026-07-09T16:36:25.25329Z","steps":["trace[1407727079] 'range keys from in-memory index tree' (duration: 103.718938ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:36:59.625849Z","caller":"traceutil/trace.go:171","msg":"trace[1276545437] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"125.259022ms","start":"2026-07-09T16:36:59.500572Z","end":"2026-07-09T16:36:59.625831Z","steps":["trace[1276545437] 'process raft request' (duration: 125.156729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.03721Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374884602325501,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:37:01.298051Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.090487661s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:37:01.298394Z","caller":"traceutil/trace.go:171","msg":"trace[1714421993] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"1.091024297s","start":"2026-07-09T16:37:00.207342Z","end":"2026-07-09T16:37:01.298366Z","steps":["trace[1714421993] 'process raft request' (duration: 1.090877233s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.298531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.207303Z","time spent":"1.091160842s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.310652Z","caller":"traceutil/trace.go:171","msg":"trace[2133469825] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.099200116s","start":"2026-07-09T16:37:00.211435Z","end":"2026-07-09T16:37:01.310636Z","steps":["trace[2133469825] 'process raft request' (duration: 1.098990279s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.31069Z","caller":"traceutil/trace.go:171","msg":"trace[428203530] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"1.096597067s","start":"2026-07-09T16:37:00.214069Z","end":"2026-07-09T16:37:01.310666Z","steps":["trace[428203530] 'process raft request' (duration: 1.096448602s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.310769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.21141Z","time spent":"1.099294388s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.310812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.214053Z","time spent":"1.09668895s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.310808Z","caller":"traceutil/trace.go:171","msg":"trace[1530575212] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"1.056820964s","start":"2026-07-09T16:37:00.253968Z","end":"2026-07-09T16:37:01.310789Z","steps":["trace[1530575212] 'process raft request' (duration: 1.056649109s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.310836Z","caller":"traceutil/trace.go:171","msg":"trace[111413454] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"1.059771203s","start":"2026-07-09T16:37:00.251012Z","end":"2026-07-09T16:37:01.310783Z","steps":["trace[111413454] 'process raft request' (duration: 1.059568777s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.310896Z","caller":"traceutil/trace.go:171","msg":"trace[2095277675] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"1.043729879s","start":"2026-07-09T16:37:00.267147Z","end":"2026-07-09T16:37:01.310877Z","steps":["trace[2095277675] 'process raft request' (duration: 1.043508382s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.310961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.267126Z","time spent":"1.043806161s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.310883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.253958Z","time spent":"1.056894606s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.310974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.25099Z","time spent":"1.059928597s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.311159Z","caller":"traceutil/trace.go:171","msg":"trace[214049124] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"1.043923625s","start":"2026-07-09T16:37:00.267226Z","end":"2026-07-09T16:37:01.311149Z","steps":["trace[214049124] 'process raft request' (duration: 1.043520013s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.311245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.267218Z","time spent":"1.043999767s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.311304Z","caller":"traceutil/trace.go:171","msg":"trace[2129412084] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"1.042395407s","start":"2026-07-09T16:37:00.268901Z","end":"2026-07-09T16:37:01.311296Z","steps":["trace[2129412084] 'process raft request' (duration: 1.042259113s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.311258Z","caller":"traceutil/trace.go:171","msg":"trace[1079732502] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"1.04375953s","start":"2026-07-09T16:37:00.267486Z","end":"2026-07-09T16:37:01.311246Z","steps":["trace[1079732502] 'process raft request' (duration: 1.043628026s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.31145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"774.808241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:01.311469Z","caller":"traceutil/trace.go:171","msg":"trace[1116705733] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"1.024251019s","start":"2026-07-09T16:37:00.287209Z","end":"2026-07-09T16:37:01.31146Z","steps":["trace[1116705733] 'process raft request' (duration: 1.024047423s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.311487Z","caller":"traceutil/trace.go:171","msg":"trace[1008227711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:576; }","duration":"774.886743ms","start":"2026-07-09T16:37:00.536592Z","end":"2026-07-09T16:37:01.311478Z","steps":["trace[1008227711] 'agreement among raft nodes before linearized reading' (duration: 774.811871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.311453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.267478Z","time spent":"1.043949185s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.311539Z","caller":"traceutil/trace.go:171","msg":"trace[793713280] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"1.032684153s","start":"2026-07-09T16:37:00.278847Z","end":"2026-07-09T16:37:01.311531Z","steps":["trace[793713280] 'process raft request' (duration: 1.032361104s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.311382Z","caller":"traceutil/trace.go:171","msg":"trace[1079716599] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:586; }","duration":"774.733899ms","start":"2026-07-09T16:37:00.536635Z","end":"2026-07-09T16:37:01.311368Z","steps":["trace[1079716599] 'read index received' (duration: 761.651983ms)","trace[1079716599] 'applied index is now lower than readState.Index' (duration: 13.081236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:01.311391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.268893Z","time spent":"1.04243929s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.311578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.278837Z","time spent":"1.032725885s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.311511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.287188Z","time spent":"1.02430467s","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:01.311649Z","caller":"traceutil/trace.go:171","msg":"trace[152696174] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"913.73158ms","start":"2026-07-09T16:37:00.397909Z","end":"2026-07-09T16:37:01.31164Z","steps":["trace[152696174] 'process raft request' (duration: 913.40866ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.31169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.39788Z","time spent":"913.792232ms","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:01.311515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.53657Z","time spent":"774.936965ms","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:01.316671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.200798ms","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-07-09T16:37:01.316707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.015152ms","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-07-09T16:37:01.316732Z","caller":"traceutil/trace.go:171","msg":"trace[852205060] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"338.318671ms","start":"2026-07-09T16:37:00.978397Z","end":"2026-07-09T16:37:01.316715Z","steps":["trace[852205060] 'agreement among raft nodes before linearized reading' (duration: 338.148936ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:01.316746Z","caller":"traceutil/trace.go:171","msg":"trace[1382016855] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"133.081704ms","start":"2026-07-09T16:37:01.183652Z","end":"2026-07-09T16:37:01.316734Z","steps":["trace[1382016855] 'agreement among raft nodes before linearized reading' (duration: 132.986761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:01.31675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.65151ms","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-07-09T16:37:01.316761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:00.978373Z","time spent":"338.381713ms","remote":"127.0.0.1:47708","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-07-09T16:37:01.316781Z","caller":"traceutil/trace.go:171","msg":"trace[1163368174] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:578; }","duration":"171.711672ms","start":"2026-07-09T16:37:01.145059Z","end":"2026-07-09T16:37:01.316771Z","steps":["trace[1163368174] 'agreement among raft nodes before linearized reading' (duration: 171.6243ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:07.878236Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14755374884602325671,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:37:08.239301Z","caller":"traceutil/trace.go:171","msg":"trace[569367541] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"926.901711ms","start":"2026-07-09T16:37:07.312319Z","end":"2026-07-09T16:37:08.239221Z","steps":["trace[569367541] 'process raft request' (duration: 926.155618ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.239609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.01721ms","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-07-09T16:37:08.239708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.312296Z","time spent":"927.251942ms","remote":"127.0.0.1:47516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:08.239719Z","caller":"traceutil/trace.go:171","msg":"trace[630853301] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:672; }","duration":"862.186725ms","start":"2026-07-09T16:37:07.377509Z","end":"2026-07-09T16:37:08.239695Z","steps":["trace[630853301] 'agreement among raft nodes before linearized reading' (duration: 861.798223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.239883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.37749Z","time spent":"862.382391ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:37:08.240361Z","caller":"traceutil/trace.go:171","msg":"trace[25876403] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"861.575587ms","start":"2026-07-09T16:37:07.377549Z","end":"2026-07-09T16:37:08.239125Z","steps":["trace[25876403] 'read index received' (duration: 860.653299ms)","trace[25876403] 'applied index is now lower than readState.Index' (duration: 920.298µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:08.240523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.446611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:08.240547Z","caller":"traceutil/trace.go:171","msg":"trace[221128513] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:672; }","duration":"496.474111ms","start":"2026-07-09T16:37:07.744063Z","end":"2026-07-09T16:37:08.240538Z","steps":["trace[221128513] 'agreement among raft nodes before linearized reading' (duration: 496.43071ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.240568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.744002Z","time spent":"496.561044ms","remote":"127.0.0.1:47656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:37:08.240606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"703.757496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:08.240663Z","caller":"traceutil/trace.go:171","msg":"trace[1672050565] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:672; }","duration":"703.827548ms","start":"2026-07-09T16:37:07.536814Z","end":"2026-07-09T16:37:08.240641Z","steps":["trace[1672050565] 'agreement among raft nodes before linearized reading' (duration: 703.696395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.240703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.536799Z","time spent":"703.88327ms","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:08.240683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.990861ms","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-07-09T16:37:08.240775Z","caller":"traceutil/trace.go:171","msg":"trace[353485721] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:672; }","duration":"562.106025ms","start":"2026-07-09T16:37:07.678639Z","end":"2026-07-09T16:37:08.240745Z","steps":["trace[353485721] 'agreement among raft nodes before linearized reading' (duration: 561.863607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.240823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.143143ms","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-07-09T16:37:08.240823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.678624Z","time spent":"562.188357ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-09T16:37:08.24085Z","caller":"traceutil/trace.go:171","msg":"trace[980520266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:672; }","duration":"856.192195ms","start":"2026-07-09T16:37:07.384651Z","end":"2026-07-09T16:37:08.240843Z","steps":["trace[980520266] 'agreement among raft nodes before linearized reading' (duration: 856.116542ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.240872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.384641Z","time spent":"856.226935ms","remote":"127.0.0.1:47708","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-07-09T16:37:08.241286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.332457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:08.241315Z","caller":"traceutil/trace.go:171","msg":"trace[1764084258] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:672; }","duration":"147.603885ms","start":"2026-07-09T16:37:08.0937Z","end":"2026-07-09T16:37:08.241304Z","steps":["trace[1764084258] 'agreement among raft nodes before linearized reading' (duration: 147.355828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.241452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.834454ms","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-07-09T16:37:08.241494Z","caller":"traceutil/trace.go:171","msg":"trace[1654112400] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:672; }","duration":"857.899066ms","start":"2026-07-09T16:37:07.38358Z","end":"2026-07-09T16:37:08.241479Z","steps":["trace[1654112400] 'agreement among raft nodes before linearized reading' (duration: 857.72385ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.241517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:07.383561Z","time spent":"857.950658ms","remote":"127.0.0.1:47708","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-07-09T16:37:09.202022Z","caller":"traceutil/trace.go:171","msg":"trace[1376644936] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"952.414084ms","start":"2026-07-09T16:37:08.249579Z","end":"2026-07-09T16:37:09.201993Z","steps":["trace[1376644936] 'process raft request' (duration: 940.463231ms)","trace[1376644936] 'compare' (duration: 11.426967ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:09.202146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:08.249568Z","time spent":"952.516628ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:09.202301Z","caller":"traceutil/trace.go:171","msg":"trace[1766190675] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"945.448283ms","start":"2026-07-09T16:37:08.256843Z","end":"2026-07-09T16:37:09.202291Z","steps":["trace[1766190675] 'process raft request' (duration: 945.126193ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:09.202348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:08.256815Z","time spent":"945.507555ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:09.202207Z","caller":"traceutil/trace.go:171","msg":"trace[2081255262] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"945.199266ms","start":"2026-07-09T16:37:08.256988Z","end":"2026-07-09T16:37:09.202187Z","steps":["trace[2081255262] 'process raft request' (duration: 945.123783ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:09.203767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:08.256982Z","time spent":"946.714871ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:09.270107Z","caller":"traceutil/trace.go:171","msg":"trace[1488172464] linearizableReadLoop","detail":"{readStateIndex:703; appliedIndex:699; }","duration":"348.512465ms","start":"2026-07-09T16:37:08.921574Z","end":"2026-07-09T16:37:09.270087Z","steps":["trace[1488172464] 'read index received' (duration: 268.40588ms)","trace[1488172464] 'applied index is now lower than readState.Index' (duration: 80.105815ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:09.270243Z","caller":"traceutil/trace.go:171","msg":"trace[214554763] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"964.263495ms","start":"2026-07-09T16:37:08.305957Z","end":"2026-07-09T16:37:09.270221Z","steps":["trace[214554763] 'process raft request' (duration: 964.028078ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:09.270347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:08.305934Z","time spent":"964.356668ms","remote":"127.0.0.1:47912","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:09.271292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.686161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:37:09.271335Z","caller":"traceutil/trace.go:171","msg":"trace[1343770256] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:677; }","duration":"349.785924ms","start":"2026-07-09T16:37:08.921538Z","end":"2026-07-09T16:37:09.271324Z","steps":["trace[1343770256] 'agreement among raft nodes before linearized reading' (duration: 348.631168ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:09.271393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:08.921515Z","time spent":"349.842446ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"warn","ts":"2026-07-09T16:37:11.535059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000015159s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-09T16:37:11.535131Z","caller":"traceutil/trace.go:171","msg":"trace[653527640] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000102421s","start":"2026-07-09T16:37:09.535011Z","end":"2026-07-09T16:37:11.535114Z","steps":["trace[653527640] 'agreement among raft nodes before linearized reading' (duration: 2.000011809s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:11.535166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:09.534954Z","time spent":"2.000204635s","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 16:37:11 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T16:37:12.511451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.849519ms","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-07-09T16:37:12.51157Z","caller":"traceutil/trace.go:171","msg":"trace[2048323887] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:677; }","duration":"421.978253ms","start":"2026-07-09T16:37:12.089573Z","end":"2026-07-09T16:37:12.511551Z","steps":["trace[2048323887] 'range keys from in-memory index tree' (duration: 421.834929ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.511807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.202920788s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:12.511919Z","caller":"traceutil/trace.go:171","msg":"trace[1641376496] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"3.03854088s","start":"2026-07-09T16:37:09.47333Z","end":"2026-07-09T16:37:12.511871Z","steps":["trace[1641376496] 'read index received' (duration: 43.161µs)","trace[1641376496] 'applied index is now lower than readState.Index' (duration: 3.038495639s)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:12.511995Z","caller":"traceutil/trace.go:171","msg":"trace[345810203] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"3.224769863s","start":"2026-07-09T16:37:09.287209Z","end":"2026-07-09T16:37:12.511979Z","steps":["trace[345810203] 'process raft request' (duration: 21.576457ms)","trace[345810203] 'compare' (duration: 3.202567817s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:12.512065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:09.287191Z","time spent":"3.224833094s","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:12.512118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.038762267s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:12.512183Z","caller":"traceutil/trace.go:171","msg":"trace[1075236036] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:678; }","duration":"3.038875331s","start":"2026-07-09T16:37:09.473289Z","end":"2026-07-09T16:37:12.512164Z","steps":["trace[1075236036] 'agreement among raft nodes before linearized reading' (duration: 3.038697365s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.512213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:09.473269Z","time spent":"3.038936572s","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:37:12.522855Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.835012573s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:37:12.523696Z","caller":"traceutil/trace.go:171","msg":"trace[228011142] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"2.837584311s","start":"2026-07-09T16:37:09.686095Z","end":"2026-07-09T16:37:12.523679Z","steps":["trace[228011142] 'process raft request' (duration: 2.837069925s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.5238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:09.686069Z","time spent":"2.837676574s","remote":"127.0.0.1:47516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036313,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:12.55473Z","caller":"traceutil/trace.go:171","msg":"trace[657038292] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"289.511539ms","start":"2026-07-09T16:37:12.265182Z","end":"2026-07-09T16:37:12.554693Z","steps":["trace[657038292] 'process raft request' (duration: 289.208219ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:12.555296Z","caller":"traceutil/trace.go:171","msg":"trace[926572000] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"264.119865ms","start":"2026-07-09T16:37:12.291131Z","end":"2026-07-09T16:37:12.555251Z","steps":["trace[926572000] 'process raft request' (duration: 263.348891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.555389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347920903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T16:37:12.555437Z","caller":"traceutil/trace.go:171","msg":"trace[401734482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"1.348007916s","start":"2026-07-09T16:37:11.207417Z","end":"2026-07-09T16:37:12.555425Z","steps":["trace[401734482] 'agreement among raft nodes before linearized reading' (duration: 1.34782281s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.555473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.2074Z","time spent":"1.348065598s","remote":"127.0.0.1:47708","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-07-09T16:37:12.555548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.347505001s","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-07-09T16:37:12.555603Z","caller":"traceutil/trace.go:171","msg":"trace[1988710782] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"1.347743208s","start":"2026-07-09T16:37:11.207849Z","end":"2026-07-09T16:37:12.555592Z","steps":["trace[1988710782] 'agreement among raft nodes before linearized reading' (duration: 1.347405678s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.555764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.192165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:37:12.555773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.207841Z","time spent":"1.347923293s","remote":"127.0.0.1:47708","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-07-09T16:37:12.556016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.248751604s","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-07-09T16:37:12.556552Z","caller":"traceutil/trace.go:171","msg":"trace[2070696228] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"2.249318541s","start":"2026-07-09T16:37:10.307218Z","end":"2026-07-09T16:37:12.556536Z","steps":["trace[2070696228] 'agreement among raft nodes before linearized reading' (duration: 2.248731874s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.5566Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:10.307196Z","time spent":"2.249392674s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:37:12.556763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021347712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:37:12.556763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350020708s","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-07-09T16:37:12.556844Z","caller":"traceutil/trace.go:171","msg":"trace[1885331514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"1.021410404s","start":"2026-07-09T16:37:11.535374Z","end":"2026-07-09T16:37:12.556785Z","steps":["trace[1885331514] 'agreement among raft nodes before linearized reading' (duration: 1.019832156s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.556906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.535354Z","time spent":"1.021546557s","remote":"127.0.0.1:47404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T16:37:12.555796Z","caller":"traceutil/trace.go:171","msg":"trace[726581760] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"655.276538ms","start":"2026-07-09T16:37:11.900511Z","end":"2026-07-09T16:37:12.555788Z","steps":["trace[726581760] 'agreement among raft nodes before linearized reading' (duration: 655.213136ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:12.556801Z","caller":"traceutil/trace.go:171","msg":"trace[1178920657] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:681; }","duration":"1.35008793s","start":"2026-07-09T16:37:11.206702Z","end":"2026-07-09T16:37:12.55679Z","steps":["trace[1178920657] 'agreement among raft nodes before linearized reading' (duration: 1.348863763s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:12.557017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.900491Z","time spent":"656.515276ms","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:12.557184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:11.206686Z","time spent":"1.350490132s","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-09T16:37:14.71439Z","caller":"traceutil/trace.go:171","msg":"trace[1495431110] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"176.453071ms","start":"2026-07-09T16:37:14.537914Z","end":"2026-07-09T16:37:14.714367Z","steps":["trace[1495431110] 'read index received' (duration: 176.268255ms)","trace[1495431110] 'applied index is now lower than readState.Index' (duration: 181.796µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:14.714447Z","caller":"traceutil/trace.go:171","msg":"trace[1781296499] transaction","detail":"{read_only:false; response_revision:720; number_of_response:1; }","duration":"329.009445ms","start":"2026-07-09T16:37:14.385409Z","end":"2026-07-09T16:37:14.714418Z","steps":["trace[1781296499] 'process raft request' (duration: 328.831548ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.714575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.631857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:37:14.714746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.38539Z","time spent":"329.301153ms","remote":"127.0.0.1:47468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13214,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:14.714797Z","caller":"traceutil/trace.go:171","msg":"trace[932631848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:720; }","duration":"176.922006ms","start":"2026-07-09T16:37:14.537862Z","end":"2026-07-09T16:37:14.714784Z","steps":["trace[932631848] 'agreement among raft nodes before linearized reading' (duration: 176.643387ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:14.718429Z","caller":"traceutil/trace.go:171","msg":"trace[1571613815] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"166.301472ms","start":"2026-07-09T16:37:14.552109Z","end":"2026-07-09T16:37:14.71841Z","steps":["trace[1571613815] 'process raft request' (duration: 165.958981ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.71887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.877783ms","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-07-09T16:37:14.718972Z","caller":"traceutil/trace.go:171","msg":"trace[2085984752] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:721; }","duration":"155.010086ms","start":"2026-07-09T16:37:14.563947Z","end":"2026-07-09T16:37:14.718957Z","steps":["trace[2085984752] 'agreement among raft nodes before linearized reading' (duration: 154.740598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.719253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.944302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T16:37:14.719259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.644524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:37:14.71931Z","caller":"traceutil/trace.go:171","msg":"trace[2003534740] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:721; }","duration":"151.005195ms","start":"2026-07-09T16:37:14.56829Z","end":"2026-07-09T16:37:14.719295Z","steps":["trace[2003534740] 'agreement among raft nodes before linearized reading' (duration: 150.787729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.719336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.58593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-09T16:37:14.719316Z","caller":"traceutil/trace.go:171","msg":"trace[1016620937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:721; }","duration":"152.726166ms","start":"2026-07-09T16:37:14.566577Z","end":"2026-07-09T16:37:14.719303Z","steps":["trace[1016620937] 'agreement among raft nodes before linearized reading' (duration: 152.577582ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:14.719391Z","caller":"traceutil/trace.go:171","msg":"trace[1880178195] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:721; }","duration":"146.663382ms","start":"2026-07-09T16:37:14.572712Z","end":"2026-07-09T16:37:14.719375Z","steps":["trace[1880178195] 'agreement among raft nodes before linearized reading' (duration: 146.516087ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:17.68707Z","caller":"traceutil/trace.go:171","msg":"trace[231831031] linearizableReadLoop","detail":"{readStateIndex:790; appliedIndex:789; }","duration":"149.611121ms","start":"2026-07-09T16:37:17.537435Z","end":"2026-07-09T16:37:17.687046Z","steps":["trace[231831031] 'read index received' (duration: 149.209909ms)","trace[231831031] 'applied index is now lower than readState.Index' (duration: 399.982µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:17.687354Z","caller":"traceutil/trace.go:171","msg":"trace[1937680196] transaction","detail":"{read_only:false; response_revision:761; number_of_response:1; }","duration":"229.304775ms","start":"2026-07-09T16:37:17.458009Z","end":"2026-07-09T16:37:17.687313Z","steps":["trace[1937680196] 'process raft request' (duration: 228.757389ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:17.688334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.287754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"warn","ts":"2026-07-09T16:37:17.687423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.971232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:17.688423Z","caller":"traceutil/trace.go:171","msg":"trace[1287797955] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:761; }","duration":"151.005343ms","start":"2026-07-09T16:37:17.537393Z","end":"2026-07-09T16:37:17.688399Z","steps":["trace[1287797955] 'agreement among raft nodes before linearized reading' (duration: 149.931091ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:17.689032Z","caller":"traceutil/trace.go:171","msg":"trace[837505104] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:761; }","duration":"138.361217ms","start":"2026-07-09T16:37:17.550026Z","end":"2026-07-09T16:37:17.688387Z","steps":["trace[837505104] 'agreement among raft nodes before linearized reading' (duration: 138.010526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.759633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.130275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.233\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-09T16:37:29.759738Z","caller":"traceutil/trace.go:171","msg":"trace[409166468] range","detail":"{range_begin:/registry/masterleases/199.19.213.233; range_end:; response_count:1; response_revision:843; }","duration":"837.270909ms","start":"2026-07-09T16:37:28.922446Z","end":"2026-07-09T16:37:29.759717Z","steps":["trace[409166468] 'range keys from in-memory index tree' (duration: 836.924378ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.759754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.411682284s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbslw\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-07-09T16:37:29.759783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.922396Z","time spent":"837.377292ms","remote":"127.0.0.1:47452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.233\" "} {"level":"info","ts":"2026-07-09T16:37:29.75982Z","caller":"traceutil/trace.go:171","msg":"trace[502785217] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbslw; range_end:; response_count:1; response_revision:843; }","duration":"1.411777996s","start":"2026-07-09T16:37:28.348027Z","end":"2026-07-09T16:37:29.759805Z","steps":["trace[502785217] 'range keys from in-memory index tree' (duration: 1.411548069s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.759847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.348013Z","time spent":"1.411827748s","remote":"127.0.0.1:47608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-hbslw\" "} {"level":"warn","ts":"2026-07-09T16:37:29.759995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378513525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T16:37:29.760011Z","caller":"traceutil/trace.go:171","msg":"trace[91503528] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:843; }","duration":"1.378559116s","start":"2026-07-09T16:37:28.381446Z","end":"2026-07-09T16:37:29.760005Z","steps":["trace[91503528] 'count revisions from in-memory index tree' (duration: 1.378429341s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.381428Z","time spent":"1.378592437s","remote":"127.0.0.1:47846","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:37:29.760135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.120046ms","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-07-09T16:37:29.76026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.223537764s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:29.760295Z","caller":"traceutil/trace.go:171","msg":"trace[1093474016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:843; }","duration":"1.223571675s","start":"2026-07-09T16:37:28.536714Z","end":"2026-07-09T16:37:29.760286Z","steps":["trace[1093474016] 'range keys from in-memory index tree' (duration: 1.22346024s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.536662Z","time spent":"1.223656897s","remote":"127.0.0.1:47412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T16:37:29.7604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.708945ms","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-07-09T16:37:29.760421Z","caller":"traceutil/trace.go:171","msg":"trace[601458989] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:843; }","duration":"966.752137ms","start":"2026-07-09T16:37:28.793663Z","end":"2026-07-09T16:37:29.760415Z","steps":["trace[601458989] 'range keys from in-memory index tree' (duration: 966.616192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.793648Z","time spent":"966.784507ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-09T16:37:29.76054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"966.848479ms","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-07-09T16:37:29.760572Z","caller":"traceutil/trace.go:171","msg":"trace[1746540704] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:843; }","duration":"966.900111ms","start":"2026-07-09T16:37:28.793663Z","end":"2026-07-09T16:37:29.760563Z","steps":["trace[1746540704] 'range keys from in-memory index tree' (duration: 966.694455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.793647Z","time spent":"966.945292ms","remote":"127.0.0.1:47708","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-07-09T16:37:29.760613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.234485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-09T16:37:29.760648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"963.778646ms","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-07-09T16:37:29.760665Z","caller":"traceutil/trace.go:171","msg":"trace[1597450245] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:843; }","duration":"963.814317ms","start":"2026-07-09T16:37:28.796845Z","end":"2026-07-09T16:37:29.760659Z","steps":["trace[1597450245] 'range keys from in-memory index tree' (duration: 963.717734ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:29.760661Z","caller":"traceutil/trace.go:171","msg":"trace[504943348] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:843; }","duration":"944.314668ms","start":"2026-07-09T16:37:28.816334Z","end":"2026-07-09T16:37:29.760648Z","steps":["trace[504943348] 'count revisions from in-memory index tree' (duration: 944.195224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.796822Z","time spent":"963.853538ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:37:29.760695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.816321Z","time spent":"944.36319ms","remote":"127.0.0.1:47500","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-07-09T16:37:29.760726Z","caller":"traceutil/trace.go:171","msg":"trace[1148031592] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:843; }","duration":"966.188559ms","start":"2026-07-09T16:37:28.793994Z","end":"2026-07-09T16:37:29.760183Z","steps":["trace[1148031592] 'range keys from in-memory index tree' (duration: 965.956842ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:29.760781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:28.793967Z","time spent":"966.800548ms","remote":"127.0.0.1:47708","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-07-09T16:37:43.32605Z","caller":"traceutil/trace.go:171","msg":"trace[1235634816] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"155.357442ms","start":"2026-07-09T16:37:43.170675Z","end":"2026-07-09T16:37:43.326032Z","steps":["trace[1235634816] 'process raft request' (duration: 154.876827ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:43.992321Z","caller":"traceutil/trace.go:171","msg":"trace[1576408012] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"166.025122ms","start":"2026-07-09T16:37:43.826273Z","end":"2026-07-09T16:37:43.992298Z","steps":["trace[1576408012] 'process raft request' (duration: 165.269368ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:50.1827Z","caller":"traceutil/trace.go:171","msg":"trace[1727181903] linearizableReadLoop","detail":"{readStateIndex:1077; appliedIndex:1076; }","duration":"150.380298ms","start":"2026-07-09T16:37:50.032298Z","end":"2026-07-09T16:37:50.182679Z","steps":["trace[1727181903] 'read index received' (duration: 150.154331ms)","trace[1727181903] 'applied index is now lower than readState.Index' (duration: 225.007µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:50.182799Z","caller":"traceutil/trace.go:171","msg":"trace[224757171] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"154.713424ms","start":"2026-07-09T16:37:50.028078Z","end":"2026-07-09T16:37:50.182792Z","steps":["trace[224757171] 'process raft request' (duration: 154.436584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:50.183013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.276326ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-09T16:37:50.183013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.656038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-09T16:37:50.183066Z","caller":"traceutil/trace.go:171","msg":"trace[455625841] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"150.359259ms","start":"2026-07-09T16:37:50.032693Z","end":"2026-07-09T16:37:50.183053Z","steps":["trace[455625841] 'agreement among raft nodes before linearized reading' (duration: 150.185214ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:50.183011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.689009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:37:50.183101Z","caller":"traceutil/trace.go:171","msg":"trace[1668404059] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"150.793702ms","start":"2026-07-09T16:37:50.032287Z","end":"2026-07-09T16:37:50.183081Z","steps":["trace[1668404059] 'agreement among raft nodes before linearized reading' (duration: 150.567285ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:50.183121Z","caller":"traceutil/trace.go:171","msg":"trace[815615989] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"150.847683ms","start":"2026-07-09T16:37:50.032258Z","end":"2026-07-09T16:37:50.183105Z","steps":["trace[815615989] 'agreement among raft nodes before linearized reading' (duration: 150.600146ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:00.461788Z","caller":"traceutil/trace.go:171","msg":"trace[21311770] linearizableReadLoop","detail":"{readStateIndex:1114; appliedIndex:1112; }","duration":"190.53906ms","start":"2026-07-09T16:38:00.271213Z","end":"2026-07-09T16:38:00.461753Z","steps":["trace[21311770] 'read index received' (duration: 34.575824ms)","trace[21311770] 'applied index is now lower than readState.Index' (duration: 155.962056ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:00.461897Z","caller":"traceutil/trace.go:171","msg":"trace[835287537] transaction","detail":"{read_only:false; response_revision:1074; number_of_response:1; }","duration":"194.595516ms","start":"2026-07-09T16:38:00.267279Z","end":"2026-07-09T16:38:00.461874Z","steps":["trace[835287537] 'process raft request' (duration: 166.154233ms)","trace[835287537] 'compare' (duration: 28.189616ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:00.462095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.85369ms","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-07-09T16:38:00.46213Z","caller":"traceutil/trace.go:171","msg":"trace[441889261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"190.927612ms","start":"2026-07-09T16:38:00.271191Z","end":"2026-07-09T16:38:00.462119Z","steps":["trace[441889261] 'agreement among raft nodes before linearized reading' (duration: 190.753037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:00.462134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.580085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:38:00.462157Z","caller":"traceutil/trace.go:171","msg":"trace[425501346] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1074; }","duration":"100.651367ms","start":"2026-07-09T16:38:00.361498Z","end":"2026-07-09T16:38:00.46215Z","steps":["trace[425501346] 'agreement among raft nodes before linearized reading' (duration: 100.597615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:00.46216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.904601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T16:38:00.462189Z","caller":"traceutil/trace.go:171","msg":"trace[373514424] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1074; }","duration":"190.968004ms","start":"2026-07-09T16:38:00.271211Z","end":"2026-07-09T16:38:00.462179Z","steps":["trace[373514424] 'agreement among raft nodes before linearized reading' (duration: 190.865021ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:02.453138Z","caller":"traceutil/trace.go:171","msg":"trace[1264310492] linearizableReadLoop","detail":"{readStateIndex:1118; appliedIndex:1117; }","duration":"143.160062ms","start":"2026-07-09T16:38:02.309955Z","end":"2026-07-09T16:38:02.453115Z","steps":["trace[1264310492] 'read index received' (duration: 142.943384ms)","trace[1264310492] 'applied index is now lower than readState.Index' (duration: 215.868µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:02.453353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.383268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-09T16:38:02.45339Z","caller":"traceutil/trace.go:171","msg":"trace[533026703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1078; }","duration":"143.43981ms","start":"2026-07-09T16:38:02.30994Z","end":"2026-07-09T16:38:02.45338Z","steps":["trace[533026703] 'agreement among raft nodes before linearized reading' (duration: 143.267375ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:02.453709Z","caller":"traceutil/trace.go:171","msg":"trace[1839501809] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"182.312728ms","start":"2026-07-09T16:38:02.271361Z","end":"2026-07-09T16:38:02.453674Z","steps":["trace[1839501809] 'process raft request' (duration: 181.616627ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:21.239988Z","caller":"traceutil/trace.go:171","msg":"trace[550441351] transaction","detail":"{read_only:false; response_revision:1377; number_of_response:1; }","duration":"307.790764ms","start":"2026-07-09T16:39:20.932176Z","end":"2026-07-09T16:39:21.239967Z","steps":["trace[550441351] 'process raft request' (duration: 307.662852ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:21.239964Z","caller":"traceutil/trace.go:171","msg":"trace[20898728] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"308.804043ms","start":"2026-07-09T16:39:20.931105Z","end":"2026-07-09T16:39:21.239909Z","steps":["trace[20898728] 'process raft request' (duration: 308.690121ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:21.239967Z","caller":"traceutil/trace.go:171","msg":"trace[930172981] transaction","detail":"{read_only:false; response_revision:1378; number_of_response:1; }","duration":"297.818574ms","start":"2026-07-09T16:39:20.942119Z","end":"2026-07-09T16:39:21.239938Z","steps":["trace[930172981] 'process raft request' (duration: 297.754433ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:21.240098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:20.932162Z","time spent":"307.884706ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:39:21.240256Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:20.93108Z","time spent":"308.988076ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:21.240591Z","caller":"traceutil/trace.go:171","msg":"trace[83271052] transaction","detail":"{read_only:false; response_revision:1375; number_of_response:1; }","duration":"308.915094ms","start":"2026-07-09T16:39:20.930958Z","end":"2026-07-09T16:39:21.239873Z","steps":["trace[83271052] 'process raft request' (duration: 307.089319ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:21.240666Z","caller":"traceutil/trace.go:171","msg":"trace[1938992185] transaction","detail":"{read_only:false; response_revision:1379; number_of_response:1; }","duration":"286.385339ms","start":"2026-07-09T16:39:20.954268Z","end":"2026-07-09T16:39:21.240653Z","steps":["trace[1938992185] 'process raft request' (duration: 286.255156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:21.240734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:39:20.930943Z","time spent":"309.7154ms","remote":"127.0.0.1:47708","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:29.094901Z","caller":"traceutil/trace.go:171","msg":"trace[764922021] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"113.337406ms","start":"2026-07-09T16:39:28.981533Z","end":"2026-07-09T16:39:29.09487Z","steps":["trace[764922021] 'read index received' (duration: 55.227804ms)","trace[764922021] 'applied index is now lower than readState.Index' (duration: 58.108912ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:29.095054Z","caller":"traceutil/trace.go:171","msg":"trace[2002807487] transaction","detail":"{read_only:false; response_revision:1406; number_of_response:1; }","duration":"160.286386ms","start":"2026-07-09T16:39:28.934752Z","end":"2026-07-09T16:39:29.095038Z","steps":["trace[2002807487] 'process raft request' (duration: 102.098731ms)","trace[2002807487] 'compare' (duration: 57.890849ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:39:29.095099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.54601ms","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-07-09T16:39:29.095161Z","caller":"traceutil/trace.go:171","msg":"trace[1939453510] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1406; }","duration":"113.657523ms","start":"2026-07-09T16:39:28.981492Z","end":"2026-07-09T16:39:29.095149Z","steps":["trace[1939453510] 'agreement among raft nodes before linearized reading' (duration: 113.465769ms)"],"step_count":1}