{"level":"warn","ts":"2026-05-20T03:09:24.892171Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:09:24.892269Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.129: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.204.45.129:2380","--initial-cluster=instance=https://199.204.45.129:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.129:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.129:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-20T03:09:24.892353Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-20T03:09:24.892365Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-20T03:09:24.892382Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:09:24.893093Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"]} {"level":"info","ts":"2026-05-20T03:09:24.893302Z","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.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.129:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-20T03:09:24.902128Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.626018ms"} {"level":"info","ts":"2026-05-20T03:09:24.908918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3cec112083e8d3ff","cluster-id":"3f68255a3b440659"} {"level":"info","ts":"2026-05-20T03:09:24.908978Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=()"} {"level":"info","ts":"2026-05-20T03:09:24.908993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 0"} {"level":"info","ts":"2026-05-20T03:09:24.909003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3cec112083e8d3ff [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-20T03:09:24.909015Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became follower at term 1"} {"level":"info","ts":"2026-05-20T03:09:24.909042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"warn","ts":"2026-05-20T03:09:24.931028Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-20T03:09:24.937686Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-20T03:09:24.93916Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-20T03:09:24.942652Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3cec112083e8d3ff","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-20T03:09:24.94281Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3cec112083e8d3ff","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-20T03:09:24.942886Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:09:24.947408Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:09:24.947451Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-20T03:09:24.951486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff switched to configuration voters=(4389902568129090559)"} {"level":"info","ts":"2026-05-20T03:09:24.951704Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","added-peer-id":"3cec112083e8d3ff","added-peer-peer-urls":["https://199.204.45.129:2380"]} {"level":"info","ts":"2026-05-20T03:09:24.95817Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-20T03:09:24.958287Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-20T03:09:24.958324Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.129:2380"} {"level":"info","ts":"2026-05-20T03:09:24.958511Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3cec112083e8d3ff","initial-advertise-peer-urls":["https://199.204.45.129:2380"],"listen-peer-urls":["https://199.204.45.129:2380"],"advertise-client-urls":["https://199.204.45.129:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.129:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-20T03:09:24.958548Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-20T03:09:25.309245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff is starting a new election at term 1"} {"level":"info","ts":"2026-05-20T03:09:25.309303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became pre-candidate at term 1"} {"level":"info","ts":"2026-05-20T03:09:25.309354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgPreVoteResp from 3cec112083e8d3ff at term 1"} {"level":"info","ts":"2026-05-20T03:09:25.309375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became candidate at term 2"} {"level":"info","ts":"2026-05-20T03:09:25.309386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff received MsgVoteResp from 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-20T03:09:25.309402Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3cec112083e8d3ff became leader at term 2"} {"level":"info","ts":"2026-05-20T03:09:25.309416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3cec112083e8d3ff elected leader 3cec112083e8d3ff at term 2"} {"level":"info","ts":"2026-05-20T03:09:25.310389Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:09:25.312381Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:09:25.312386Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3cec112083e8d3ff","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.129:2379]}","request-path":"/0/members/3cec112083e8d3ff/attributes","cluster-id":"3f68255a3b440659","publish-timeout":"7s"} {"level":"info","ts":"2026-05-20T03:09:25.312648Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-20T03:09:25.313333Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-20T03:09:25.313461Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-20T03:09:25.314064Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3f68255a3b440659","local-member-id":"3cec112083e8d3ff","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:09:25.31422Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:09:25.314261Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-20T03:09:25.316318Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-20T03:09:25.316354Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.129:2379"} {"level":"info","ts":"2026-05-20T03:09:36.318922Z","caller":"traceutil/trace.go:171","msg":"trace[1191121060] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"169.757708ms","start":"2026-05-20T03:09:36.149136Z","end":"2026-05-20T03:09:36.318894Z","steps":["trace[1191121060] 'process raft request' (duration: 127.954123ms)","trace[1191121060] 'compare' (duration: 41.525469ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:36.318922Z","caller":"traceutil/trace.go:171","msg":"trace[1845730362] linearizableReadLoop","detail":"{readStateIndex:351; appliedIndex:350; }","duration":"139.431597ms","start":"2026-05-20T03:09:36.17946Z","end":"2026-05-20T03:09:36.318891Z","steps":["trace[1845730362] 'read index received' (duration: 97.656842ms)","trace[1845730362] 'applied index is now lower than readState.Index' (duration: 41.773465ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:36.31908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.59675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-05-20T03:09:36.319168Z","caller":"traceutil/trace.go:171","msg":"trace[2045887207] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:343; }","duration":"139.753233ms","start":"2026-05-20T03:09:36.179404Z","end":"2026-05-20T03:09:36.319157Z","steps":["trace[2045887207] 'agreement among raft nodes before linearized reading' (duration: 139.550269ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:42.952936Z","caller":"traceutil/trace.go:171","msg":"trace[1422529566] linearizableReadLoop","detail":"{readStateIndex:425; appliedIndex:424; }","duration":"150.469851ms","start":"2026-05-20T03:09:42.802436Z","end":"2026-05-20T03:09:42.952906Z","steps":["trace[1422529566] 'read index received' (duration: 150.280698ms)","trace[1422529566] 'applied index is now lower than readState.Index' (duration: 177.073µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:42.953Z","caller":"traceutil/trace.go:171","msg":"trace[1794513731] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"539.808109ms","start":"2026-05-20T03:09:42.413145Z","end":"2026-05-20T03:09:42.952953Z","steps":["trace[1794513731] 'process raft request' (duration: 539.572445ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:42.953193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.728147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:09:42.953314Z","caller":"traceutil/trace.go:171","msg":"trace[1000401635] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:415; }","duration":"150.932182ms","start":"2026-05-20T03:09:42.802355Z","end":"2026-05-20T03:09:42.953287Z","steps":["trace[1000401635] 'agreement among raft nodes before linearized reading' (duration: 150.657555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:42.954471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:42.413124Z","time spent":"540.026384ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:09:43.48161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207918765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:09:43.982451Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207918765,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:09:44.041025Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.081438715s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:09:44.041597Z","caller":"traceutil/trace.go:171","msg":"trace[1283904080] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"1.082107468s","start":"2026-05-20T03:09:42.959464Z","end":"2026-05-20T03:09:44.041572Z","steps":["trace[1283904080] 'process raft request' (duration: 1.081923915s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:44.041765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:42.959437Z","time spent":"1.08221965s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:09:44.58716Z","caller":"traceutil/trace.go:171","msg":"trace[191520187] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:425; }","duration":"1.606097099s","start":"2026-05-20T03:09:42.981037Z","end":"2026-05-20T03:09:44.587134Z","steps":["trace[191520187] 'read index received' (duration: 1.060284822s)","trace[191520187] 'applied index is now lower than readState.Index' (duration: 545.810747ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:44.587377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.453834592s","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-05-20T03:09:44.587419Z","caller":"traceutil/trace.go:171","msg":"trace[582071010] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:416; }","duration":"1.453953314s","start":"2026-05-20T03:09:43.133454Z","end":"2026-05-20T03:09:44.587408Z","steps":["trace[582071010] 'agreement among raft nodes before linearized reading' (duration: 1.453831452s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:44.587394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.606324594s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T03:09:44.587508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:43.133423Z","time spent":"1.454028406s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:09:44.587583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.579344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:09:44.58761Z","caller":"traceutil/trace.go:171","msg":"trace[928320602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:416; }","duration":"211.643454ms","start":"2026-05-20T03:09:44.375961Z","end":"2026-05-20T03:09:44.587604Z","steps":["trace[928320602] 'agreement among raft nodes before linearized reading' (duration: 211.603133ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:44.587512Z","caller":"traceutil/trace.go:171","msg":"trace[400720211] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:416; }","duration":"1.606506388s","start":"2026-05-20T03:09:42.980972Z","end":"2026-05-20T03:09:44.587479Z","steps":["trace[400720211] 'agreement among raft nodes before linearized reading' (duration: 1.606233243s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:44.587787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:42.980944Z","time spent":"1.606833373s","remote":"127.0.0.1:45522","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-05-20T03:09:44.781429Z","caller":"traceutil/trace.go:171","msg":"trace[1537240738] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"188.215148ms","start":"2026-05-20T03:09:44.59318Z","end":"2026-05-20T03:09:44.781395Z","steps":["trace[1537240738] 'process raft request' (duration: 187.993034ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:44.825951Z","caller":"traceutil/trace.go:171","msg":"trace[1512294953] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"232.691725ms","start":"2026-05-20T03:09:44.593235Z","end":"2026-05-20T03:09:44.825927Z","steps":["trace[1512294953] 'process raft request' (duration: 232.321108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.417001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207918885,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T03:09:53.462828Z","caller":"traceutil/trace.go:171","msg":"trace[619927960] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"600.992514ms","start":"2026-05-20T03:09:52.861809Z","end":"2026-05-20T03:09:53.462802Z","steps":["trace[619927960] 'process raft request' (duration: 600.81196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.462969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.861785Z","time spent":"601.108135ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:09:53.53225Z","caller":"traceutil/trace.go:171","msg":"trace[1444155322] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"603.940309ms","start":"2026-05-20T03:09:52.928266Z","end":"2026-05-20T03:09:53.532206Z","steps":["trace[1444155322] 'process raft request' (duration: 603.610683ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:53.532408Z","caller":"traceutil/trace.go:171","msg":"trace[1068635246] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"599.058073ms","start":"2026-05-20T03:09:52.933317Z","end":"2026-05-20T03:09:53.532375Z","steps":["trace[1068635246] 'process raft request' (duration: 598.860519ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.532563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.933293Z","time spent":"599.192337ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.532686Z","caller":"traceutil/trace.go:171","msg":"trace[422942901] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"566.645642ms","start":"2026-05-20T03:09:52.966003Z","end":"2026-05-20T03:09:53.532649Z","steps":["trace[422942901] 'process raft request' (duration: 566.247864ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.532743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.928252Z","time spent":"604.099872ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:09:53.532797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.965989Z","time spent":"566.760665ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.532264Z","caller":"traceutil/trace.go:171","msg":"trace[2008534017] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"599.189626ms","start":"2026-05-20T03:09:52.933037Z","end":"2026-05-20T03:09:53.532227Z","steps":["trace[2008534017] 'process raft request' (duration: 599.051104ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:53.533089Z","caller":"traceutil/trace.go:171","msg":"trace[1537625777] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"566.735754ms","start":"2026-05-20T03:09:52.966336Z","end":"2026-05-20T03:09:53.533072Z","steps":["trace[1537625777] 'process raft request' (duration: 566.362387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.533104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.933028Z","time spent":"599.928062ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:09:53.533208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.965928Z","time spent":"567.212394ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.53357Z","caller":"traceutil/trace.go:171","msg":"trace[57433193] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"567.103941ms","start":"2026-05-20T03:09:52.966446Z","end":"2026-05-20T03:09:53.53355Z","steps":["trace[57433193] 'process raft request' (duration: 566.829606ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.533646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.966419Z","time spent":"567.193923ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.536465Z","caller":"traceutil/trace.go:171","msg":"trace[790353402] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"553.268122ms","start":"2026-05-20T03:09:52.983176Z","end":"2026-05-20T03:09:53.536444Z","steps":["trace[790353402] 'process raft request' (duration: 552.214282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.536704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.983163Z","time spent":"553.358304ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.537051Z","caller":"traceutil/trace.go:171","msg":"trace[1080613712] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"561.890471ms","start":"2026-05-20T03:09:52.975143Z","end":"2026-05-20T03:09:53.537033Z","steps":["trace[1080613712] 'process raft request' (duration: 558.29728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.53714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.975127Z","time spent":"561.974683ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.537694Z","caller":"traceutil/trace.go:171","msg":"trace[705851322] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"560.141526ms","start":"2026-05-20T03:09:52.977532Z","end":"2026-05-20T03:09:53.537674Z","steps":["trace[705851322] 'process raft request' (duration: 557.646997ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.538727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.977526Z","time spent":"560.214677ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.538795Z","caller":"traceutil/trace.go:171","msg":"trace[886961516] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"533.842803ms","start":"2026-05-20T03:09:53.004936Z","end":"2026-05-20T03:09:53.538779Z","steps":["trace[886961516] 'process raft request' (duration: 530.59125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.538871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:53.004907Z","time spent":"533.928304ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.542017Z","caller":"traceutil/trace.go:171","msg":"trace[457190707] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"440.398832ms","start":"2026-05-20T03:09:53.101584Z","end":"2026-05-20T03:09:53.541983Z","steps":["trace[457190707] 'process raft request' (duration: 434.060449ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.542082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:53.101562Z","time spent":"440.487884ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:09:53.542393Z","caller":"traceutil/trace.go:171","msg":"trace[958372668] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"558.994105ms","start":"2026-05-20T03:09:52.98338Z","end":"2026-05-20T03:09:53.542374Z","steps":["trace[958372668] 'process raft request' (duration: 552.08009ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:53.54241Z","caller":"traceutil/trace.go:171","msg":"trace[1319433420] linearizableReadLoop","detail":"{readStateIndex:495; appliedIndex:482; }","duration":"625.694703ms","start":"2026-05-20T03:09:52.916695Z","end":"2026-05-20T03:09:53.54239Z","steps":["trace[1319433420] 'read index received' (duration: 545.942549ms)","trace[1319433420] 'applied index is now lower than readState.Index' (duration: 79.748234ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:09:53.542522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.983354Z","time spent":"559.077976ms","remote":"127.0.0.1:45318","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:09:53.552929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.172317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:09:53.552984Z","caller":"traceutil/trace.go:171","msg":"trace[495278995] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:483; }","duration":"636.3238ms","start":"2026-05-20T03:09:52.916647Z","end":"2026-05-20T03:09:53.552971Z","steps":["trace[495278995] 'agreement among raft nodes before linearized reading' (duration: 625.870027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.553013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:52.916622Z","time spent":"636.385012ms","remote":"127.0.0.1:45522","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-05-20T03:09:53.553606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.3557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:09:53.55363Z","caller":"traceutil/trace.go:171","msg":"trace[2025408159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"543.409681ms","start":"2026-05-20T03:09:53.010214Z","end":"2026-05-20T03:09:53.553624Z","steps":["trace[2025408159] 'agreement among raft nodes before linearized reading' (duration: 543.36263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:53.553643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:53.010195Z","time spent":"543.445431ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:09:53.553818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.337795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:09:53.553858Z","caller":"traceutil/trace.go:171","msg":"trace[1106815757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"178.424227ms","start":"2026-05-20T03:09:53.375424Z","end":"2026-05-20T03:09:53.553849Z","steps":["trace[1106815757] 'agreement among raft nodes before linearized reading' (duration: 178.314185ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:53.95525Z","caller":"traceutil/trace.go:171","msg":"trace[1155633001] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"147.653367ms","start":"2026-05-20T03:09:53.807567Z","end":"2026-05-20T03:09:53.955221Z","steps":["trace[1155633001] 'process raft request' (duration: 99.07206ms)","trace[1155633001] 'compare' (duration: 48.411143ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:53.970235Z","caller":"traceutil/trace.go:171","msg":"trace[1444166244] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"122.275292ms","start":"2026-05-20T03:09:53.847943Z","end":"2026-05-20T03:09:53.970218Z","steps":["trace[1444166244] 'process raft request' (duration: 122.246451ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:53.9705Z","caller":"traceutil/trace.go:171","msg":"trace[2088848443] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"154.637683ms","start":"2026-05-20T03:09:53.815857Z","end":"2026-05-20T03:09:53.970495Z","steps":["trace[2088848443] 'process raft request' (duration: 154.239236ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:09:54.198511Z","caller":"traceutil/trace.go:171","msg":"trace[432554364] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:538; }","duration":"116.786105ms","start":"2026-05-20T03:09:54.081701Z","end":"2026-05-20T03:09:54.198487Z","steps":["trace[432554364] 'read index received' (duration: 69.442172ms)","trace[432554364] 'applied index is now lower than readState.Index' (duration: 47.342753ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:54.198515Z","caller":"traceutil/trace.go:171","msg":"trace[1995252241] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"192.236646ms","start":"2026-05-20T03:09:54.006246Z","end":"2026-05-20T03:09:54.198483Z","steps":["trace[1995252241] 'process raft request' (duration: 144.971735ms)","trace[1995252241] 'compare' (duration: 46.911995ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:09:54.198595Z","caller":"traceutil/trace.go:171","msg":"trace[1715649309] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"155.944438ms","start":"2026-05-20T03:09:54.042601Z","end":"2026-05-20T03:09:54.198545Z","steps":["trace[1715649309] 'process raft request' (duration: 155.742585ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:09:54.198762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.04739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:09:54.198815Z","caller":"traceutil/trace.go:171","msg":"trace[35852620] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:524; }","duration":"117.183433ms","start":"2026-05-20T03:09:54.081615Z","end":"2026-05-20T03:09:54.198799Z","steps":["trace[35852620] 'agreement among raft nodes before linearized reading' (duration: 116.966479ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:00.297977Z","caller":"traceutil/trace.go:171","msg":"trace[499928249] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"517.041306ms","start":"2026-05-20T03:09:59.780864Z","end":"2026-05-20T03:10:00.297905Z","steps":["trace[499928249] 'process raft request' (duration: 516.370393ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:00.298315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:09:59.780846Z","time spent":"517.369964ms","remote":"127.0.0.1:45370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:00.894077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.198794ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:10:00.894209Z","caller":"traceutil/trace.go:171","msg":"trace[1171256873] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"591.388924ms","start":"2026-05-20T03:10:00.3028Z","end":"2026-05-20T03:10:00.894189Z","steps":["trace[1171256873] 'process raft request' (duration: 256.790383ms)","trace[1171256873] 'compare' (duration: 334.051121ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:00.894272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:00.302777Z","time spent":"591.467296ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:01.146478Z","caller":"traceutil/trace.go:171","msg":"trace[1963388143] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:597; }","duration":"666.682902ms","start":"2026-05-20T03:10:00.47977Z","end":"2026-05-20T03:10:01.146453Z","steps":["trace[1963388143] 'read index received' (duration: 79.825785ms)","trace[1963388143] 'applied index is now lower than readState.Index' (duration: 586.855917ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:01.146532Z","caller":"traceutil/trace.go:171","msg":"trace[1645818671] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"784.452327ms","start":"2026-05-20T03:10:00.362047Z","end":"2026-05-20T03:10:01.146499Z","steps":["trace[1645818671] 'process raft request' (duration: 752.563906ms)","trace[1645818671] 'compare' (duration: 31.697457ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:01.146797Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"667.008349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-q4xpx\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-20T03:10:01.147041Z","caller":"traceutil/trace.go:171","msg":"trace[749058181] range","detail":"{range_begin:/registry/pods/kube-system/cilium-q4xpx; range_end:; response_count:1; response_revision:582; }","duration":"667.300134ms","start":"2026-05-20T03:10:00.47972Z","end":"2026-05-20T03:10:01.14702Z","steps":["trace[749058181] 'agreement among raft nodes before linearized reading' (duration: 666.843056ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:01.14709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:00.479698Z","time spent":"667.379106ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-q4xpx\" "} {"level":"warn","ts":"2026-05-20T03:10:01.147192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:00.362026Z","time spent":"784.894046ms","remote":"127.0.0.1:45788","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:10:01.163966Z","caller":"traceutil/trace.go:171","msg":"trace[698132699] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"303.145908ms","start":"2026-05-20T03:10:00.8608Z","end":"2026-05-20T03:10:01.163946Z","steps":["trace[698132699] 'process raft request' (duration: 302.953314ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:01.16422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:00.860777Z","time spent":"303.367442ms","remote":"127.0.0.1:45454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:02.753439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.020003677s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:10:02.753565Z","caller":"traceutil/trace.go:171","msg":"trace[2120433400] linearizableReadLoop","detail":"{readStateIndex:602; appliedIndex:601; }","duration":"1.378799289s","start":"2026-05-20T03:10:01.37474Z","end":"2026-05-20T03:10:02.753539Z","steps":["trace[2120433400] 'read index received' (duration: 358.643469ms)","trace[2120433400] 'applied index is now lower than readState.Index' (duration: 1.02015371s)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:02.753797Z","caller":"traceutil/trace.go:171","msg":"trace[1556434985] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"1.589512226s","start":"2026-05-20T03:10:01.164272Z","end":"2026-05-20T03:10:02.753784Z","steps":["trace[1556434985] 'process raft request' (duration: 569.07537ms)","trace[1556434985] 'compare' (duration: 1.019711682s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:02.753874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.164255Z","time spent":"1.589580967s","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:02.753904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.379136626s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:02.75399Z","caller":"traceutil/trace.go:171","msg":"trace[1042428197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"1.37925458s","start":"2026-05-20T03:10:01.374711Z","end":"2026-05-20T03:10:02.753966Z","steps":["trace[1042428197] 'agreement among raft nodes before linearized reading' (duration: 1.379090276s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.754065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.374697Z","time spent":"1.379323901s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T03:10:02.908982Z","caller":"traceutil/trace.go:171","msg":"trace[1215917995] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"1.361031464s","start":"2026-05-20T03:10:01.54792Z","end":"2026-05-20T03:10:02.908952Z","steps":["trace[1215917995] 'process raft request' (duration: 1.335451806s)","trace[1215917995] 'compare' (duration: 25.17852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:02.909654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.54788Z","time spent":"1.361718307s","remote":"127.0.0.1:45370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:02.909111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.307143593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:10:02.90987Z","caller":"traceutil/trace.go:171","msg":"trace[1004660711] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:586; }","duration":"1.307951939s","start":"2026-05-20T03:10:01.601903Z","end":"2026-05-20T03:10:02.909855Z","steps":["trace[1004660711] 'agreement among raft nodes before linearized reading' (duration: 1.307063841s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.910185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.60188Z","time spent":"1.308294556s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:10:02.908899Z","caller":"traceutil/trace.go:171","msg":"trace[1598861618] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"155.273896ms","start":"2026-05-20T03:10:02.753599Z","end":"2026-05-20T03:10:02.908873Z","steps":["trace[1598861618] 'read index received' (duration: 129.552025ms)","trace[1598861618] 'applied index is now lower than readState.Index' (duration: 25.720721ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:02.909543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.414351052s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T03:10:02.910518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.728955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:02.910519Z","caller":"traceutil/trace.go:171","msg":"trace[232624360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:586; }","duration":"1.41534107s","start":"2026-05-20T03:10:01.49514Z","end":"2026-05-20T03:10:02.910481Z","steps":["trace[232624360] 'agreement among raft nodes before linearized reading' (duration: 1.414216739s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:02.910571Z","caller":"traceutil/trace.go:171","msg":"trace[1166350266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"153.851338ms","start":"2026-05-20T03:10:02.7567Z","end":"2026-05-20T03:10:02.910551Z","steps":["trace[1166350266] 'agreement among raft nodes before linearized reading' (duration: 153.779886ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.9106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.495125Z","time spent":"1.415457543s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:10:02.909515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310778986s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:10:02.910652Z","caller":"traceutil/trace.go:171","msg":"trace[1491204486] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"1.311979527s","start":"2026-05-20T03:10:01.598657Z","end":"2026-05-20T03:10:02.910636Z","steps":["trace[1491204486] 'agreement among raft nodes before linearized reading' (duration: 1.310364076s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:02.910699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:01.598634Z","time spent":"1.31205125s","remote":"127.0.0.1:45522","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-05-20T03:10:09.903681Z","caller":"traceutil/trace.go:171","msg":"trace[1553227567] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"120.213121ms","start":"2026-05-20T03:10:09.78342Z","end":"2026-05-20T03:10:09.903634Z","steps":["trace[1553227567] 'process raft request' (duration: 119.862334ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:15.728036Z","caller":"traceutil/trace.go:171","msg":"trace[1455925325] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"137.60918ms","start":"2026-05-20T03:10:15.590403Z","end":"2026-05-20T03:10:15.728013Z","steps":["trace[1455925325] 'process raft request' (duration: 137.391366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:18.818371Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207919453,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T03:10:18.859783Z","caller":"traceutil/trace.go:171","msg":"trace[1317796392] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"989.32063ms","start":"2026-05-20T03:10:17.870438Z","end":"2026-05-20T03:10:18.859758Z","steps":["trace[1317796392] 'process raft request' (duration: 989.110935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:18.859941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:17.870413Z","time spent":"989.447912ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:18.867533Z","caller":"traceutil/trace.go:171","msg":"trace[6709304] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"549.501042ms","start":"2026-05-20T03:10:18.317985Z","end":"2026-05-20T03:10:18.867486Z","steps":["trace[6709304] 'read index received' (duration: 541.994396ms)","trace[6709304] 'applied index is now lower than readState.Index' (duration: 7.505566ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:18.867571Z","caller":"traceutil/trace.go:171","msg":"trace[700304646] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"261.429261ms","start":"2026-05-20T03:10:18.606128Z","end":"2026-05-20T03:10:18.867558Z","steps":["trace[700304646] 'process raft request' (duration: 261.256658ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:18.867706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.712465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T03:10:18.867739Z","caller":"traceutil/trace.go:171","msg":"trace[1880849687] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:750; }","duration":"549.789537ms","start":"2026-05-20T03:10:18.31794Z","end":"2026-05-20T03:10:18.86773Z","steps":["trace[1880849687] 'agreement among raft nodes before linearized reading' (duration: 549.661205ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:18.867775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:18.317922Z","time spent":"549.845138ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.129\" "} {"level":"warn","ts":"2026-05-20T03:10:23.708359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.453513ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-20T03:10:23.708609Z","caller":"traceutil/trace.go:171","msg":"trace[1264488265] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"194.380926ms","start":"2026-05-20T03:10:23.514214Z","end":"2026-05-20T03:10:23.708595Z","steps":["trace[1264488265] 'process raft request' (duration: 194.240283ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:41.552325Z","caller":"traceutil/trace.go:171","msg":"trace[905111209] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"217.70531ms","start":"2026-05-20T03:10:41.334586Z","end":"2026-05-20T03:10:41.552291Z","steps":["trace[905111209] 'process raft request' (duration: 212.100611ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:41.552111Z","caller":"traceutil/trace.go:171","msg":"trace[158824682] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"217.254101ms","start":"2026-05-20T03:10:41.334828Z","end":"2026-05-20T03:10:41.552082Z","steps":["trace[158824682] 'process raft request' (duration: 217.084078ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:41.552932Z","caller":"traceutil/trace.go:171","msg":"trace[799484809] linearizableReadLoop","detail":"{readStateIndex:950; appliedIndex:946; }","duration":"179.393914ms","start":"2026-05-20T03:10:41.373511Z","end":"2026-05-20T03:10:41.552905Z","steps":["trace[799484809] 'read index received' (duration: 47.819372ms)","trace[799484809] 'applied index is now lower than readState.Index' (duration: 131.571422ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:41.553465Z","caller":"traceutil/trace.go:171","msg":"trace[1221221065] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"218.136129ms","start":"2026-05-20T03:10:41.335274Z","end":"2026-05-20T03:10:41.55341Z","steps":["trace[1221221065] 'process raft request' (duration: 216.760752ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:41.553503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.974376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:41.553653Z","caller":"traceutil/trace.go:171","msg":"trace[201012005] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:923; }","duration":"180.128868ms","start":"2026-05-20T03:10:41.373474Z","end":"2026-05-20T03:10:41.553603Z","steps":["trace[201012005] 'agreement among raft nodes before linearized reading' (duration: 179.509977ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:50.607405Z","caller":"traceutil/trace.go:171","msg":"trace[1785763681] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"995.915837ms","start":"2026-05-20T03:10:49.611454Z","end":"2026-05-20T03:10:50.60737Z","steps":["trace[1785763681] 'process raft request' (duration: 931.511353ms)","trace[1785763681] 'compare' (duration: 64.007837ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:50.607567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:49.611426Z","time spent":"996.053479ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:50.607787Z","caller":"traceutil/trace.go:171","msg":"trace[1078364474] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"995.706783ms","start":"2026-05-20T03:10:49.611627Z","end":"2026-05-20T03:10:50.607334Z","steps":["trace[1078364474] 'process raft request' (duration: 995.614152ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:50.607976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:49.611618Z","time spent":"996.268055ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:50.635342Z","caller":"traceutil/trace.go:171","msg":"trace[1180357287] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:977; }","duration":"258.454264ms","start":"2026-05-20T03:10:50.376868Z","end":"2026-05-20T03:10:50.635322Z","steps":["trace[1180357287] 'read index received' (duration: 166.331099ms)","trace[1180357287] 'applied index is now lower than readState.Index' (duration: 92.122125ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:10:50.635607Z","caller":"traceutil/trace.go:171","msg":"trace[198647442] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"350.607108ms","start":"2026-05-20T03:10:50.284987Z","end":"2026-05-20T03:10:50.635594Z","steps":["trace[198647442] 'process raft request' (duration: 350.218411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:50.635647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.806296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T03:10:50.635674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.796961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:10:50.63571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:50.284962Z","time spent":"350.679041ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:50.635717Z","caller":"traceutil/trace.go:171","msg":"trace[464485051] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:951; }","duration":"208.91274ms","start":"2026-05-20T03:10:50.426787Z","end":"2026-05-20T03:10:50.6357Z","steps":["trace[464485051] 'agreement among raft nodes before linearized reading' (duration: 208.797567ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:50.635753Z","caller":"traceutil/trace.go:171","msg":"trace[1187444110] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:951; }","duration":"258.920313ms","start":"2026-05-20T03:10:50.376814Z","end":"2026-05-20T03:10:50.635734Z","steps":["trace[1187444110] 'agreement among raft nodes before linearized reading' (duration: 258.78439ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:52.19036Z","caller":"traceutil/trace.go:171","msg":"trace[1795820884] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"149.076353ms","start":"2026-05-20T03:10:52.041256Z","end":"2026-05-20T03:10:52.190332Z","steps":["trace[1795820884] 'process raft request' (duration: 148.921281ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:57.282177Z","caller":"traceutil/trace.go:171","msg":"trace[127387474] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"644.788189ms","start":"2026-05-20T03:10:56.637366Z","end":"2026-05-20T03:10:57.282154Z","steps":["trace[127387474] 'process raft request' (duration: 644.604814ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:57.2823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:56.637354Z","time spent":"644.88326ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:10:58.412255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"593.767525ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:10:58.4124Z","caller":"traceutil/trace.go:171","msg":"trace[157743230] linearizableReadLoop","detail":"{readStateIndex:1004; appliedIndex:1001; }","duration":"1.243794876s","start":"2026-05-20T03:10:57.16859Z","end":"2026-05-20T03:10:58.412385Z","steps":["trace[157743230] 'read index received' (duration: 113.425369ms)","trace[157743230] 'applied index is now lower than readState.Index' (duration: 1.130368747s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:58.412525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.243936728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:10:58.412548Z","caller":"traceutil/trace.go:171","msg":"trace[1784847483] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:974; }","duration":"1.24399674s","start":"2026-05-20T03:10:57.168543Z","end":"2026-05-20T03:10:58.41254Z","steps":["trace[1784847483] 'agreement among raft nodes before linearized reading' (duration: 1.243927458s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:58.412574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:57.168522Z","time spent":"1.24404498s","remote":"127.0.0.1:45372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:10:58.41273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.093394796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:10:58.412755Z","caller":"traceutil/trace.go:171","msg":"trace[689838544] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:974; }","duration":"1.093455277s","start":"2026-05-20T03:10:57.31929Z","end":"2026-05-20T03:10:58.412745Z","steps":["trace[689838544] 'agreement among raft nodes before linearized reading' (duration: 1.093368516s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:58.412775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:57.319231Z","time spent":"1.093538839s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:10:58.412863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.629699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:10:58.41289Z","caller":"traceutil/trace.go:171","msg":"trace[379026164] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:974; }","duration":"693.68558ms","start":"2026-05-20T03:10:57.719189Z","end":"2026-05-20T03:10:58.412883Z","steps":["trace[379026164] 'agreement among raft nodes before linearized reading' (duration: 693.611799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:58.412913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:57.719175Z","time spent":"693.733521ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:10:58.412936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"815.069825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:10:58.412959Z","caller":"traceutil/trace.go:171","msg":"trace[1213015521] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:974; }","duration":"815.130355ms","start":"2026-05-20T03:10:57.597822Z","end":"2026-05-20T03:10:58.412952Z","steps":["trace[1213015521] 'agreement among raft nodes before linearized reading' (duration: 815.062374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:58.412979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:57.597768Z","time spent":"815.205907ms","remote":"127.0.0.1:45522","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-05-20T03:10:58.412987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.037299703s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:10:58.412424Z","caller":"traceutil/trace.go:171","msg":"trace[65636145] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"1.761858375s","start":"2026-05-20T03:10:56.650553Z","end":"2026-05-20T03:10:58.412412Z","steps":["trace[65636145] 'process raft request' (duration: 1.761780824s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:10:58.413055Z","caller":"traceutil/trace.go:171","msg":"trace[1521106787] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:974; }","duration":"1.037408235s","start":"2026-05-20T03:10:57.37563Z","end":"2026-05-20T03:10:58.413038Z","steps":["trace[1521106787] 'agreement among raft nodes before linearized reading' (duration: 1.037280702s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:10:58.4131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:57.375613Z","time spent":"1.037479487s","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:10:58.413125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:56.65054Z","time spent":"1.762528838s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:10:58.413707Z","caller":"traceutil/trace.go:171","msg":"trace[261472887] transaction","detail":"{read_only:false; response_revision:973; number_of_response:1; }","duration":"1.773760177s","start":"2026-05-20T03:10:56.638639Z","end":"2026-05-20T03:10:58.4124Z","steps":["trace[261472887] 'process raft request' (duration: 1.179777148s)","trace[261472887] 'compare' (duration: 593.513419ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:10:58.41439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:10:56.638614Z","time spent":"1.775705505s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:19.882474Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920114,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-20T03:11:20.088829Z","caller":"traceutil/trace.go:171","msg":"trace[1776473025] linearizableReadLoop","detail":"{readStateIndex:1080; appliedIndex:1079; }","duration":"706.940124ms","start":"2026-05-20T03:11:19.381866Z","end":"2026-05-20T03:11:20.088806Z","steps":["trace[1776473025] 'read index received' (duration: 706.805841ms)","trace[1776473025] 'applied index is now lower than readState.Index' (duration: 133.363µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:20.088914Z","caller":"traceutil/trace.go:171","msg":"trace[1011984295] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"714.174715ms","start":"2026-05-20T03:11:19.374712Z","end":"2026-05-20T03:11:20.088887Z","steps":["trace[1011984295] 'process raft request' (duration: 713.94975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.089028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.143398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:11:20.089103Z","caller":"traceutil/trace.go:171","msg":"trace[1612283772] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1045; }","duration":"707.23595ms","start":"2026-05-20T03:11:19.381855Z","end":"2026-05-20T03:11:20.089091Z","steps":["trace[1612283772] 'agreement among raft nodes before linearized reading' (duration: 707.065897ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.089123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:19.374691Z","time spent":"714.360469ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:20.089121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.176473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:11:20.089172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:19.381823Z","time spent":"707.336071ms","remote":"127.0.0.1:45714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":29,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:11:20.089215Z","caller":"traceutil/trace.go:171","msg":"trace[1425724318] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:1045; }","duration":"544.346326ms","start":"2026-05-20T03:11:19.544846Z","end":"2026-05-20T03:11:20.089192Z","steps":["trace[1425724318] 'agreement among raft nodes before linearized reading' (duration: 544.167853ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.089272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:19.544823Z","time spent":"544.438687ms","remote":"127.0.0.1:43444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":130,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:11:20.811648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"613.759545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:20.811874Z","caller":"traceutil/trace.go:171","msg":"trace[1200071678] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1045; }","duration":"614.028019ms","start":"2026-05-20T03:11:20.197825Z","end":"2026-05-20T03:11:20.811853Z","steps":["trace[1200071678] 'count revisions from in-memory index tree' (duration: 613.598001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.811922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:20.197807Z","time spent":"614.102791ms","remote":"127.0.0.1:43530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:11:20.811893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.046591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:20.811987Z","caller":"traceutil/trace.go:171","msg":"trace[1123557954] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1045; }","duration":"436.164944ms","start":"2026-05-20T03:11:20.375808Z","end":"2026-05-20T03:11:20.811973Z","steps":["trace[1123557954] 'range keys from in-memory index tree' (duration: 435.998031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.812023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:20.375798Z","time spent":"436.218645ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:11:20.811964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.751634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:11:20.812147Z","caller":"traceutil/trace.go:171","msg":"trace[1100486648] linearizableReadLoop","detail":"{readStateIndex:1081; appliedIndex:1080; }","duration":"272.668377ms","start":"2026-05-20T03:11:20.539469Z","end":"2026-05-20T03:11:20.812138Z","steps":["trace[1100486648] 'read index received' (duration: 94.654629ms)","trace[1100486648] 'applied index is now lower than readState.Index' (duration: 178.010808ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:20.812208Z","caller":"traceutil/trace.go:171","msg":"trace[1107875758] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"296.373999ms","start":"2026-05-20T03:11:20.515824Z","end":"2026-05-20T03:11:20.812198Z","steps":["trace[1107875758] 'process raft request' (duration: 118.314099ms)","trace[1107875758] 'compare' (duration: 177.361556ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:20.812274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.813031ms","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-05-20T03:11:20.81231Z","caller":"traceutil/trace.go:171","msg":"trace[782860018] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1046; }","duration":"272.880231ms","start":"2026-05-20T03:11:20.539417Z","end":"2026-05-20T03:11:20.812297Z","steps":["trace[782860018] 'agreement among raft nodes before linearized reading' (duration: 272.775959ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:20.838583Z","caller":"traceutil/trace.go:171","msg":"trace[764118880] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"165.905114ms","start":"2026-05-20T03:11:20.672658Z","end":"2026-05-20T03:11:20.838563Z","steps":["trace[764118880] 'process raft request' (duration: 165.809542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.838798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.325436ms","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-05-20T03:11:20.838871Z","caller":"traceutil/trace.go:171","msg":"trace[299674899] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1047; }","duration":"247.446417ms","start":"2026-05-20T03:11:20.591408Z","end":"2026-05-20T03:11:20.838855Z","steps":["trace[299674899] 'agreement among raft nodes before linearized reading' (duration: 247.301195ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.838924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.565782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:11:20.839019Z","caller":"traceutil/trace.go:171","msg":"trace[1960665885] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1047; }","duration":"259.658484ms","start":"2026-05-20T03:11:20.579322Z","end":"2026-05-20T03:11:20.83898Z","steps":["trace[1960665885] 'agreement among raft nodes before linearized reading' (duration: 259.4457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.839119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.490372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:11:20.839165Z","caller":"traceutil/trace.go:171","msg":"trace[913961397] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1047; }","duration":"257.558184ms","start":"2026-05-20T03:11:20.581592Z","end":"2026-05-20T03:11:20.83915Z","steps":["trace[913961397] 'agreement among raft nodes before linearized reading' (duration: 257.481182ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:20.839025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.137211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:11:20.839253Z","caller":"traceutil/trace.go:171","msg":"trace[1712656032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1047; }","duration":"264.407046ms","start":"2026-05-20T03:11:20.574797Z","end":"2026-05-20T03:11:20.839204Z","steps":["trace[1712656032] 'agreement among raft nodes before linearized reading' (duration: 264.032769ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:21.082765Z","caller":"traceutil/trace.go:171","msg":"trace[847143889] transaction","detail":"{read_only:false; response_revision:1050; number_of_response:1; }","duration":"238.838849ms","start":"2026-05-20T03:11:20.843896Z","end":"2026-05-20T03:11:21.082735Z","steps":["trace[847143889] 'process raft request' (duration: 238.737127ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:21.083085Z","caller":"traceutil/trace.go:171","msg":"trace[1086539949] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"239.080765ms","start":"2026-05-20T03:11:20.843988Z","end":"2026-05-20T03:11:21.083069Z","steps":["trace[1086539949] 'process raft request' (duration: 238.700017ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:21.083249Z","caller":"traceutil/trace.go:171","msg":"trace[720060446] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"239.862681ms","start":"2026-05-20T03:11:20.843284Z","end":"2026-05-20T03:11:21.083147Z","steps":["trace[720060446] 'process raft request' (duration: 202.058106ms)","trace[720060446] 'compare' (duration: 37.145741ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:22.87598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:11:23.376372Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920130,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:11:23.831935Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.733589739s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:11:23.832308Z","caller":"traceutil/trace.go:171","msg":"trace[535945407] transaction","detail":"{read_only:false; response_revision:1052; number_of_response:1; }","duration":"1.734042487s","start":"2026-05-20T03:11:22.098242Z","end":"2026-05-20T03:11:23.832284Z","steps":["trace[535945407] 'process raft request' (duration: 1.733893074s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:23.832451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.098225Z","time spent":"1.734154909s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:24.002979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.990571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:11:24.00326Z","caller":"traceutil/trace.go:171","msg":"trace[838529199] transaction","detail":"{read_only:false; response_revision:1053; number_of_response:1; }","duration":"1.185518891s","start":"2026-05-20T03:11:22.817711Z","end":"2026-05-20T03:11:24.00323Z","steps":["trace[838529199] 'process raft request' (duration: 1.083187063s)","trace[838529199] 'compare' (duration: 101.849949ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:24.003391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.817683Z","time spent":"1.185664894s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:24.003452Z","caller":"traceutil/trace.go:171","msg":"trace[1716895473] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"1.006426212s","start":"2026-05-20T03:11:22.996994Z","end":"2026-05-20T03:11:24.003421Z","steps":["trace[1716895473] 'process raft request' (duration: 1.006138936s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.003627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.996968Z","time spent":"1.006568554s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:24.116398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.046617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-20T03:11:24.116564Z","caller":"traceutil/trace.go:171","msg":"trace[851182601] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1086; }","duration":"1.741243056s","start":"2026-05-20T03:11:22.375306Z","end":"2026-05-20T03:11:24.116549Z","steps":["trace[851182601] 'read index received' (duration: 1.456811371s)","trace[851182601] 'applied index is now lower than readState.Index' (duration: 284.430295ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:24.116659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.741344138s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:24.116767Z","caller":"traceutil/trace.go:171","msg":"trace[588555466] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1054; }","duration":"1.741496892s","start":"2026-05-20T03:11:22.375261Z","end":"2026-05-20T03:11:24.116758Z","steps":["trace[588555466] 'agreement among raft nodes before linearized reading' (duration: 1.74135259s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.116809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.37524Z","time spent":"1.741561243s","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:11:24.117086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.548265848s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:11:24.117146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029728774s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:11:24.117226Z","caller":"traceutil/trace.go:171","msg":"trace[239691004] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.029841047s","start":"2026-05-20T03:11:23.087377Z","end":"2026-05-20T03:11:24.117218Z","steps":["trace[239691004] 'agreement among raft nodes before linearized reading' (duration: 1.029688644s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.117264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:23.087348Z","time spent":"1.029909339s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:11:24.117246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.100471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T03:11:24.117322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029911709s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:11:24.117346Z","caller":"traceutil/trace.go:171","msg":"trace[1631014969] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1054; }","duration":"160.254474ms","start":"2026-05-20T03:11:23.957074Z","end":"2026-05-20T03:11:24.117329Z","steps":["trace[1631014969] 'agreement among raft nodes before linearized reading' (duration: 160.06262ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:24.117359Z","caller":"traceutil/trace.go:171","msg":"trace[337296176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.029992929s","start":"2026-05-20T03:11:23.087358Z","end":"2026-05-20T03:11:24.117351Z","steps":["trace[337296176] 'agreement among raft nodes before linearized reading' (duration: 1.029854187s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.1174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:23.087335Z","time spent":"1.030057381s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T03:11:24.117185Z","caller":"traceutil/trace.go:171","msg":"trace[850403895] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:1054; }","duration":"1.548416891s","start":"2026-05-20T03:11:22.568749Z","end":"2026-05-20T03:11:24.117166Z","steps":["trace[850403895] 'agreement among raft nodes before linearized reading' (duration: 1.548250027s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.117557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.568729Z","time spent":"1.548816419s","remote":"127.0.0.1:45558","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":27,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:11:24.117617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.030165903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:11:24.117724Z","caller":"traceutil/trace.go:171","msg":"trace[2141389619] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"1.030324386s","start":"2026-05-20T03:11:23.087381Z","end":"2026-05-20T03:11:24.117705Z","steps":["trace[2141389619] 'agreement among raft nodes before linearized reading' (duration: 1.029880648s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.117777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:23.087353Z","time spent":"1.030414858s","remote":"127.0.0.1:45522","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-05-20T03:11:24.117731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230019306s","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-05-20T03:11:24.117924Z","caller":"traceutil/trace.go:171","msg":"trace[395691975] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"1.230265971s","start":"2026-05-20T03:11:22.887649Z","end":"2026-05-20T03:11:24.117915Z","steps":["trace[395691975] 'agreement among raft nodes before linearized reading' (duration: 1.229960245s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:24.11797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:22.88762Z","time spent":"1.230340492s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-20T03:11:24.393544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.949434ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:11:24.393905Z","caller":"traceutil/trace.go:171","msg":"trace[1242831493] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"269.066427ms","start":"2026-05-20T03:11:24.12481Z","end":"2026-05-20T03:11:24.393876Z","steps":["trace[1242831493] 'process raft request' (duration: 137.681415ms)","trace[1242831493] 'compare' (duration: 130.832182ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:24.393983Z","caller":"traceutil/trace.go:171","msg":"trace[1488312254] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"268.317332ms","start":"2026-05-20T03:11:24.12562Z","end":"2026-05-20T03:11:24.393937Z","steps":["trace[1488312254] 'process raft request' (duration: 268.055028ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:24.394135Z","caller":"traceutil/trace.go:171","msg":"trace[1072094985] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"268.708571ms","start":"2026-05-20T03:11:24.12541Z","end":"2026-05-20T03:11:24.394119Z","steps":["trace[1072094985] 'process raft request' (duration: 268.220142ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.535135Z","caller":"traceutil/trace.go:171","msg":"trace[1289492340] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"526.04184ms","start":"2026-05-20T03:11:26.009075Z","end":"2026-05-20T03:11:26.535117Z","steps":["trace[1289492340] 'process raft request' (duration: 525.834575ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.535281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.009047Z","time spent":"526.158202ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:26.535491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"443.539527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:26.535588Z","caller":"traceutil/trace.go:171","msg":"trace[462815545] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1060; }","duration":"443.735211ms","start":"2026-05-20T03:11:26.091831Z","end":"2026-05-20T03:11:26.535566Z","steps":["trace[462815545] 'agreement among raft nodes before linearized reading' (duration: 443.478056ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.535715Z","caller":"traceutil/trace.go:171","msg":"trace[440633051] linearizableReadLoop","detail":"{readStateIndex:1096; appliedIndex:1095; }","duration":"443.179701ms","start":"2026-05-20T03:11:26.09189Z","end":"2026-05-20T03:11:26.535069Z","steps":["trace[440633051] 'read index received' (duration: 442.952455ms)","trace[440633051] 'applied index is now lower than readState.Index' (duration: 226.346µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:26.536036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.273457ms","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-05-20T03:11:26.536077Z","caller":"traceutil/trace.go:171","msg":"trace[1895020828] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1060; }","duration":"137.360358ms","start":"2026-05-20T03:11:26.398707Z","end":"2026-05-20T03:11:26.536067Z","steps":["trace[1895020828] 'agreement among raft nodes before linearized reading' (duration: 137.214366ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.536077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.416252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T03:11:26.536172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.129584ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-20T03:11:26.536242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.321811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:11:26.536318Z","caller":"traceutil/trace.go:171","msg":"trace[437421417] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1060; }","duration":"393.409803ms","start":"2026-05-20T03:11:26.142871Z","end":"2026-05-20T03:11:26.536281Z","steps":["trace[437421417] 'agreement among raft nodes before linearized reading' (duration: 393.205869ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.536262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.024559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:11:26.536364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.142852Z","time spent":"393.502205ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-20T03:11:26.536403Z","caller":"traceutil/trace.go:171","msg":"trace[150727125] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:1060; }","duration":"265.203702ms","start":"2026-05-20T03:11:26.271189Z","end":"2026-05-20T03:11:26.536393Z","steps":["trace[150727125] 'agreement among raft nodes before linearized reading' (duration: 265.022379ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:26.536308Z","caller":"traceutil/trace.go:171","msg":"trace[1232473558] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1060; }","duration":"137.355888ms","start":"2026-05-20T03:11:26.39891Z","end":"2026-05-20T03:11:26.536265Z","steps":["trace[1232473558] 'agreement among raft nodes before linearized reading' (duration: 137.047052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.53644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.656074ms","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-05-20T03:11:26.536201Z","caller":"traceutil/trace.go:171","msg":"trace[1093981496] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1060; }","duration":"238.552085ms","start":"2026-05-20T03:11:26.297632Z","end":"2026-05-20T03:11:26.536184Z","steps":["trace[1093981496] 'agreement among raft nodes before linearized reading' (duration: 238.361811ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.535657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.091808Z","time spent":"443.837762ms","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:11:26.536676Z","caller":"traceutil/trace.go:171","msg":"trace[617239757] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1060; }","duration":"137.978221ms","start":"2026-05-20T03:11:26.398665Z","end":"2026-05-20T03:11:26.536643Z","steps":["trace[617239757] 'agreement among raft nodes before linearized reading' (duration: 137.563362ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.844244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.325398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:11:26.844357Z","caller":"traceutil/trace.go:171","msg":"trace[406604076] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"303.069977ms","start":"2026-05-20T03:11:26.54127Z","end":"2026-05-20T03:11:26.84434Z","steps":["trace[406604076] 'process raft request' (duration: 77.580876ms)","trace[406604076] 'compare' (duration: 225.224286ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:26.844414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.541248Z","time spent":"303.139779ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:26.86983Z","caller":"traceutil/trace.go:171","msg":"trace[1971093354] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"328.483761ms","start":"2026-05-20T03:11:26.541321Z","end":"2026-05-20T03:11:26.869805Z","steps":["trace[1971093354] 'process raft request' (duration: 328.317207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.87003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.541302Z","time spent":"328.611484ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:26.870219Z","caller":"traceutil/trace.go:171","msg":"trace[1543866151] transaction","detail":"{read_only:false; response_revision:1063; number_of_response:1; }","duration":"327.910661ms","start":"2026-05-20T03:11:26.542273Z","end":"2026-05-20T03:11:26.870184Z","steps":["trace[1543866151] 'process raft request' (duration: 327.490263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.870349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.542259Z","time spent":"328.022012ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:26.870577Z","caller":"traceutil/trace.go:171","msg":"trace[1067669596] transaction","detail":"{read_only:false; response_revision:1064; number_of_response:1; }","duration":"328.039263ms","start":"2026-05-20T03:11:26.542523Z","end":"2026-05-20T03:11:26.870562Z","steps":["trace[1067669596] 'process raft request' (duration: 327.620045ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:26.87098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:26.542505Z","time spent":"328.209635ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:42.139881Z","caller":"traceutil/trace.go:171","msg":"trace[966630141] linearizableReadLoop","detail":"{readStateIndex:1186; appliedIndex:1183; }","duration":"164.623228ms","start":"2026-05-20T03:11:41.975237Z","end":"2026-05-20T03:11:42.13986Z","steps":["trace[966630141] 'read index received' (duration: 1.140672ms)","trace[966630141] 'applied index is now lower than readState.Index' (duration: 163.478866ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:42.139975Z","caller":"traceutil/trace.go:171","msg":"trace[585969983] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"166.484055ms","start":"2026-05-20T03:11:41.973468Z","end":"2026-05-20T03:11:42.139952Z","steps":["trace[585969983] 'process raft request' (duration: 166.307912ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.139989Z","caller":"traceutil/trace.go:171","msg":"trace[793957211] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"166.094947ms","start":"2026-05-20T03:11:41.973881Z","end":"2026-05-20T03:11:42.139976Z","steps":["trace[793957211] 'process raft request' (duration: 165.928554ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.139885Z","caller":"traceutil/trace.go:171","msg":"trace[850887294] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"169.572814ms","start":"2026-05-20T03:11:41.970283Z","end":"2026-05-20T03:11:42.139856Z","steps":["trace[850887294] 'process raft request' (duration: 130.145808ms)","trace[850887294] 'compare' (duration: 39.219732ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:42.140154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.904264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hn9lw\" ","response":"range_response_count:1 size:9695"} {"level":"info","ts":"2026-05-20T03:11:42.140189Z","caller":"traceutil/trace.go:171","msg":"trace[2092179703] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-hn9lw; range_end:; response_count:1; response_revision:1145; }","duration":"164.985435ms","start":"2026-05-20T03:11:41.975194Z","end":"2026-05-20T03:11:42.140179Z","steps":["trace[2092179703] 'agreement among raft nodes before linearized reading' (duration: 164.807632ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.150574Z","caller":"traceutil/trace.go:171","msg":"trace[1677130462] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"168.251378ms","start":"2026-05-20T03:11:41.982306Z","end":"2026-05-20T03:11:42.150558Z","steps":["trace[1677130462] 'process raft request' (duration: 168.166057ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.150582Z","caller":"traceutil/trace.go:171","msg":"trace[1161891364] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"169.298269ms","start":"2026-05-20T03:11:41.981246Z","end":"2026-05-20T03:11:42.150544Z","steps":["trace[1161891364] 'process raft request' (duration: 169.181097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:42.150628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.130389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:11:42.150695Z","caller":"traceutil/trace.go:171","msg":"trace[75792087] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1148; }","duration":"163.227781ms","start":"2026-05-20T03:11:41.987453Z","end":"2026-05-20T03:11:42.150681Z","steps":["trace[75792087] 'agreement among raft nodes before linearized reading' (duration: 163.055857ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.150654Z","caller":"traceutil/trace.go:171","msg":"trace[1366063754] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"173.076092ms","start":"2026-05-20T03:11:41.977515Z","end":"2026-05-20T03:11:42.150591Z","steps":["trace[1366063754] 'process raft request' (duration: 172.809727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:42.150964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.603612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:6 size:363686"} {"level":"info","ts":"2026-05-20T03:11:42.150991Z","caller":"traceutil/trace.go:171","msg":"trace[1996294975] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:6; response_revision:1148; }","duration":"123.666603ms","start":"2026-05-20T03:11:42.027317Z","end":"2026-05-20T03:11:42.150984Z","steps":["trace[1996294975] 'agreement among raft nodes before linearized reading' (duration: 123.527341ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.285956Z","caller":"traceutil/trace.go:171","msg":"trace[2128953391] transaction","detail":"{read_only:false; response_revision:1152; number_of_response:1; }","duration":"130.873081ms","start":"2026-05-20T03:11:42.155058Z","end":"2026-05-20T03:11:42.285931Z","steps":["trace[2128953391] 'process raft request' (duration: 130.738359ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.286192Z","caller":"traceutil/trace.go:171","msg":"trace[662733369] transaction","detail":"{read_only:false; response_revision:1153; number_of_response:1; }","duration":"125.897866ms","start":"2026-05-20T03:11:42.160269Z","end":"2026-05-20T03:11:42.286167Z","steps":["trace[662733369] 'process raft request' (duration: 125.56375ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.286232Z","caller":"traceutil/trace.go:171","msg":"trace[500587579] transaction","detail":"{read_only:false; response_revision:1150; number_of_response:1; }","duration":"132.066175ms","start":"2026-05-20T03:11:42.154149Z","end":"2026-05-20T03:11:42.286215Z","steps":["trace[500587579] 'process raft request' (duration: 94.928674ms)","trace[500587579] 'compare' (duration: 36.517829ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:42.286207Z","caller":"traceutil/trace.go:171","msg":"trace[938971716] linearizableReadLoop","detail":"{readStateIndex:1193; appliedIndex:1189; }","duration":"127.745991ms","start":"2026-05-20T03:11:42.158435Z","end":"2026-05-20T03:11:42.286181Z","steps":["trace[938971716] 'read index received' (duration: 67.979281ms)","trace[938971716] 'applied index is now lower than readState.Index' (duration: 59.76366ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:42.286352Z","caller":"traceutil/trace.go:171","msg":"trace[181127537] transaction","detail":"{read_only:false; response_revision:1151; number_of_response:1; }","duration":"132.025984ms","start":"2026-05-20T03:11:42.154311Z","end":"2026-05-20T03:11:42.286337Z","steps":["trace[181127537] 'process raft request' (duration: 131.426213ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:42.286433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.981035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:10872"} {"level":"info","ts":"2026-05-20T03:11:42.28646Z","caller":"traceutil/trace.go:171","msg":"trace[1804332488] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1155; }","duration":"128.040497ms","start":"2026-05-20T03:11:42.158412Z","end":"2026-05-20T03:11:42.286452Z","steps":["trace[1804332488] 'agreement among raft nodes before linearized reading' (duration: 127.905735ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.515463Z","caller":"traceutil/trace.go:171","msg":"trace[1870740967] linearizableReadLoop","detail":"{readStateIndex:1200; appliedIndex:1199; }","duration":"169.076465ms","start":"2026-05-20T03:11:42.346361Z","end":"2026-05-20T03:11:42.515437Z","steps":["trace[1870740967] 'read index received' (duration: 108.956228ms)","trace[1870740967] 'applied index is now lower than readState.Index' (duration: 60.119177ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:42.515577Z","caller":"traceutil/trace.go:171","msg":"trace[673809153] transaction","detail":"{read_only:false; response_revision:1159; number_of_response:1; }","duration":"196.09709ms","start":"2026-05-20T03:11:42.319436Z","end":"2026-05-20T03:11:42.515533Z","steps":["trace[673809153] 'process raft request' (duration: 135.898121ms)","trace[673809153] 'compare' (duration: 59.974935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:42.515704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.32987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/ceph-csi-rbd-provisioner\" ","response":"range_response_count:1 size:11182"} {"level":"info","ts":"2026-05-20T03:11:42.515746Z","caller":"traceutil/trace.go:171","msg":"trace[1562162037] range","detail":"{range_begin:/registry/deployments/kube-system/ceph-csi-rbd-provisioner; range_end:; response_count:1; response_revision:1159; }","duration":"169.411931ms","start":"2026-05-20T03:11:42.346323Z","end":"2026-05-20T03:11:42.515735Z","steps":["trace[1562162037] 'agreement among raft nodes before linearized reading' (duration: 169.248848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:42.515704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.266973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:42.515831Z","caller":"traceutil/trace.go:171","msg":"trace[1072335811] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1159; }","duration":"142.439367ms","start":"2026-05-20T03:11:42.373374Z","end":"2026-05-20T03:11:42.515814Z","steps":["trace[1072335811] 'agreement among raft nodes before linearized reading' (duration: 142.263994ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:42.718361Z","caller":"traceutil/trace.go:171","msg":"trace[983267360] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"189.945921ms","start":"2026-05-20T03:11:42.528369Z","end":"2026-05-20T03:11:42.718315Z","steps":["trace[983267360] 'process raft request' (duration: 149.524725ms)","trace[983267360] 'compare' (duration: 39.930037ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:44.700717Z","caller":"traceutil/trace.go:171","msg":"trace[1911283947] linearizableReadLoop","detail":"{readStateIndex:1212; appliedIndex:1211; }","duration":"326.000484ms","start":"2026-05-20T03:11:44.374689Z","end":"2026-05-20T03:11:44.70069Z","steps":["trace[1911283947] 'read index received' (duration: 325.894812ms)","trace[1911283947] 'applied index is now lower than readState.Index' (duration: 104.242µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:44.700857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.159517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:44.700888Z","caller":"traceutil/trace.go:171","msg":"trace[964281976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1170; }","duration":"326.248859ms","start":"2026-05-20T03:11:44.37463Z","end":"2026-05-20T03:11:44.700879Z","steps":["trace[964281976] 'agreement among raft nodes before linearized reading' (duration: 326.160687ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:44.700928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:44.374588Z","time spent":"326.33346ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-20T03:11:44.701323Z","caller":"traceutil/trace.go:171","msg":"trace[852882868] transaction","detail":"{read_only:false; response_revision:1170; number_of_response:1; }","duration":"404.467538ms","start":"2026-05-20T03:11:44.296841Z","end":"2026-05-20T03:11:44.701309Z","steps":["trace[852882868] 'process raft request' (duration: 403.667763ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:44.701413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:44.296819Z","time spent":"404.540949ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:44.702609Z","caller":"traceutil/trace.go:171","msg":"trace[77619545] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"288.017056ms","start":"2026-05-20T03:11:44.414579Z","end":"2026-05-20T03:11:44.702596Z","steps":["trace[77619545] 'process raft request' (duration: 287.927034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:47.478327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920406,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:11:47.760385Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.050283714s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:11:47.760808Z","caller":"traceutil/trace.go:171","msg":"trace[2086391223] transaction","detail":"{read_only:false; response_revision:1177; number_of_response:1; }","duration":"1.050756873s","start":"2026-05-20T03:11:46.710022Z","end":"2026-05-20T03:11:47.760779Z","steps":["trace[2086391223] 'process raft request' (duration: 1.050575279s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:47.76096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:46.710005Z","time spent":"1.050875756s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:48.09326Z","caller":"traceutil/trace.go:171","msg":"trace[1579883490] linearizableReadLoop","detail":"{readStateIndex:1220; appliedIndex:1218; }","duration":"1.114996672s","start":"2026-05-20T03:11:46.978237Z","end":"2026-05-20T03:11:48.093234Z","steps":["trace[1579883490] 'read index received' (duration: 782.347619ms)","trace[1579883490] 'applied index is now lower than readState.Index' (duration: 332.648083ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:48.093566Z","caller":"traceutil/trace.go:171","msg":"trace[1346075759] transaction","detail":"{read_only:false; response_revision:1178; number_of_response:1; }","duration":"1.360676324s","start":"2026-05-20T03:11:46.732856Z","end":"2026-05-20T03:11:48.093533Z","steps":["trace[1346075759] 'process raft request' (duration: 1.351647798s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:48.093649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.09077883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:11:48.093705Z","caller":"traceutil/trace.go:171","msg":"trace[1449091081] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1178; }","duration":"1.090865232s","start":"2026-05-20T03:11:47.002823Z","end":"2026-05-20T03:11:48.093688Z","steps":["trace[1449091081] 'agreement among raft nodes before linearized reading' (duration: 1.090697349s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:48.093713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:46.732834Z","time spent":"1.360800386s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:48.093749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:47.002809Z","time spent":"1.090931283s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:11:48.093938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.015309915s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:11:48.093985Z","caller":"traceutil/trace.go:171","msg":"trace[1451994781] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1178; }","duration":"1.015395406s","start":"2026-05-20T03:11:47.078577Z","end":"2026-05-20T03:11:48.093972Z","steps":["trace[1451994781] 'agreement among raft nodes before linearized reading' (duration: 1.015275494s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:48.094038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:47.078557Z","time spent":"1.015470848s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:11:48.094033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115764436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:11:48.09405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.510528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:11:48.093986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05115035s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:11:48.094111Z","caller":"traceutil/trace.go:171","msg":"trace[44242107] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1178; }","duration":"1.115888179s","start":"2026-05-20T03:11:46.978202Z","end":"2026-05-20T03:11:48.09409Z","steps":["trace[44242107] 'agreement among raft nodes before linearized reading' (duration: 1.115713885s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:48.094139Z","caller":"traceutil/trace.go:171","msg":"trace[472011143] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1178; }","duration":"718.646411ms","start":"2026-05-20T03:11:47.375471Z","end":"2026-05-20T03:11:48.094117Z","steps":["trace[472011143] 'agreement among raft nodes before linearized reading' (duration: 718.486378ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:48.094144Z","caller":"traceutil/trace.go:171","msg":"trace[1354404921] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1178; }","duration":"1.051326644s","start":"2026-05-20T03:11:47.042797Z","end":"2026-05-20T03:11:48.094123Z","steps":["trace[1354404921] 'agreement among raft nodes before linearized reading' (duration: 1.05110191s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:48.094158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:46.978188Z","time spent":"1.11596023s","remote":"127.0.0.1:43366","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:11:48.094201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:47.375457Z","time spent":"718.726352ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:11:48.094401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:47.042784Z","time spent":"1.051399815s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T03:11:48.093631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.014706054s","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-05-20T03:11:48.094742Z","caller":"traceutil/trace.go:171","msg":"trace[1533907478] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1178; }","duration":"1.015847665s","start":"2026-05-20T03:11:47.078874Z","end":"2026-05-20T03:11:48.094722Z","steps":["trace[1533907478] 'agreement among raft nodes before linearized reading' (duration: 1.014643782s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:48.094795Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:47.078861Z","time spent":"1.015921017s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T03:11:50.265217Z","caller":"traceutil/trace.go:171","msg":"trace[1072660064] transaction","detail":"{read_only:false; response_revision:1187; number_of_response:1; }","duration":"119.209446ms","start":"2026-05-20T03:11:50.145989Z","end":"2026-05-20T03:11:50.265199Z","steps":["trace[1072660064] 'process raft request' (duration: 75.777832ms)","trace[1072660064] 'compare' (duration: 43.21094ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:50.26527Z","caller":"traceutil/trace.go:171","msg":"trace[1390506878] transaction","detail":"{read_only:false; response_revision:1188; number_of_response:1; }","duration":"118.890261ms","start":"2026-05-20T03:11:50.146349Z","end":"2026-05-20T03:11:50.265239Z","steps":["trace[1390506878] 'process raft request' (duration: 118.753668ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:50.265307Z","caller":"traceutil/trace.go:171","msg":"trace[53455604] transaction","detail":"{read_only:false; response_revision:1189; number_of_response:1; }","duration":"118.875799ms","start":"2026-05-20T03:11:50.146412Z","end":"2026-05-20T03:11:50.265287Z","steps":["trace[53455604] 'process raft request' (duration: 118.781278ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:52.250862Z","caller":"traceutil/trace.go:171","msg":"trace[1129852096] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"104.335577ms","start":"2026-05-20T03:11:52.146496Z","end":"2026-05-20T03:11:52.250831Z","steps":["trace[1129852096] 'process raft request' (duration: 104.118723ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:56.439049Z","caller":"traceutil/trace.go:171","msg":"trace[1116469121] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"140.079842ms","start":"2026-05-20T03:11:56.298955Z","end":"2026-05-20T03:11:56.439035Z","steps":["trace[1116469121] 'process raft request' (duration: 140.040931ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:56.43926Z","caller":"traceutil/trace.go:171","msg":"trace[1423768778] transaction","detail":"{read_only:false; response_revision:1207; number_of_response:1; }","duration":"140.49538ms","start":"2026-05-20T03:11:56.298758Z","end":"2026-05-20T03:11:56.439253Z","steps":["trace[1423768778] 'process raft request' (duration: 110.726061ms)","trace[1423768778] 'compare' (duration: 29.208687ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:56.439317Z","caller":"traceutil/trace.go:171","msg":"trace[1295012827] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"140.438739ms","start":"2026-05-20T03:11:56.298874Z","end":"2026-05-20T03:11:56.439313Z","steps":["trace[1295012827] 'process raft request' (duration: 140.094081ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:59.198191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.193307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:11:59.198257Z","caller":"traceutil/trace.go:171","msg":"trace[2070727499] linearizableReadLoop","detail":"{readStateIndex:1259; appliedIndex:1258; }","duration":"695.599784ms","start":"2026-05-20T03:11:58.502647Z","end":"2026-05-20T03:11:59.198246Z","steps":["trace[2070727499] 'read index received' (duration: 420.408708ms)","trace[2070727499] 'applied index is now lower than readState.Index' (duration: 275.190296ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:11:59.198347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.699576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T03:11:59.198319Z","caller":"traceutil/trace.go:171","msg":"trace[633221275] transaction","detail":"{read_only:false; response_revision:1214; number_of_response:1; }","duration":"696.94527ms","start":"2026-05-20T03:11:58.50134Z","end":"2026-05-20T03:11:59.198285Z","steps":["trace[633221275] 'process raft request' (duration: 421.59966ms)","trace[633221275] 'compare' (duration: 274.934222ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:59.198366Z","caller":"traceutil/trace.go:171","msg":"trace[1972105438] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:1214; }","duration":"695.741566ms","start":"2026-05-20T03:11:58.502618Z","end":"2026-05-20T03:11:59.19836Z","steps":["trace[1972105438] 'agreement among raft nodes before linearized reading' (duration: 695.657285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:59.198389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:58.502604Z","time spent":"695.779807ms","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-05-20T03:11:59.198452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:58.501325Z","time spent":"697.054041ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:11:59.58019Z","caller":"traceutil/trace.go:171","msg":"trace[1018084318] linearizableReadLoop","detail":"{readStateIndex:1261; appliedIndex:1259; }","duration":"379.730087ms","start":"2026-05-20T03:11:59.200434Z","end":"2026-05-20T03:11:59.580164Z","steps":["trace[1018084318] 'read index received' (duration: 379.582364ms)","trace[1018084318] 'applied index is now lower than readState.Index' (duration: 146.813µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:11:59.580261Z","caller":"traceutil/trace.go:171","msg":"trace[893134536] transaction","detail":"{read_only:false; response_revision:1215; number_of_response:1; }","duration":"1.076056405s","start":"2026-05-20T03:11:58.504172Z","end":"2026-05-20T03:11:59.580228Z","steps":["trace[893134536] 'process raft request' (duration: 1.07580161s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:59.580307Z","caller":"traceutil/trace.go:171","msg":"trace[1495162420] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"1.075943342s","start":"2026-05-20T03:11:58.504322Z","end":"2026-05-20T03:11:59.580265Z","steps":["trace[1495162420] 'process raft request' (duration: 1.075800219s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:59.580424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:58.504161Z","time spent":"1.076184977s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:59.580483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:58.504316Z","time spent":"1.076082915s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:11:59.580479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.016562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"warn","ts":"2026-05-20T03:11:59.580428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.780578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:11:59.580557Z","caller":"traceutil/trace.go:171","msg":"trace[1575528964] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1216; }","duration":"380.141075ms","start":"2026-05-20T03:11:59.200398Z","end":"2026-05-20T03:11:59.580539Z","steps":["trace[1575528964] 'agreement among raft nodes before linearized reading' (duration: 379.920171ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:11:59.58056Z","caller":"traceutil/trace.go:171","msg":"trace[296243241] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"204.966242ms","start":"2026-05-20T03:11:59.375581Z","end":"2026-05-20T03:11:59.580547Z","steps":["trace[296243241] 'agreement among raft nodes before linearized reading' (duration: 204.745858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:11:59.5806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:11:59.200381Z","time spent":"380.211256ms","remote":"127.0.0.1:45532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-20T03:12:00.008035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.459521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:12:00.008088Z","caller":"traceutil/trace.go:171","msg":"trace[1474112100] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1216; }","duration":"135.554524ms","start":"2026-05-20T03:11:59.872519Z","end":"2026-05-20T03:12:00.008073Z","steps":["trace[1474112100] 'range keys from in-memory index tree' (duration: 135.320699ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:00.501085Z","caller":"traceutil/trace.go:171","msg":"trace[789601429] linearizableReadLoop","detail":"{readStateIndex:1263; appliedIndex:1262; }","duration":"125.205712ms","start":"2026-05-20T03:12:00.375857Z","end":"2026-05-20T03:12:00.501062Z","steps":["trace[789601429] 'read index received' (duration: 125.008658ms)","trace[789601429] 'applied index is now lower than readState.Index' (duration: 196.124µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:00.501256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.376006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:00.50132Z","caller":"traceutil/trace.go:171","msg":"trace[1853099518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1218; }","duration":"125.482348ms","start":"2026-05-20T03:12:00.375824Z","end":"2026-05-20T03:12:00.501307Z","steps":["trace[1853099518] 'agreement among raft nodes before linearized reading' (duration: 125.357466ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:00.501202Z","caller":"traceutil/trace.go:171","msg":"trace[1521557138] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"172.286867ms","start":"2026-05-20T03:12:00.328893Z","end":"2026-05-20T03:12:00.50118Z","steps":["trace[1521557138] 'process raft request' (duration: 172.019542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:01.717608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.35761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:01.717659Z","caller":"traceutil/trace.go:171","msg":"trace[1049677177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1220; }","duration":"343.447353ms","start":"2026-05-20T03:12:01.374199Z","end":"2026-05-20T03:12:01.717647Z","steps":["trace[1049677177] 'range keys from in-memory index tree' (duration: 343.294389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:01.717683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:01.374181Z","time spent":"343.495034ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:01.717668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.303871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:12:01.717741Z","caller":"traceutil/trace.go:171","msg":"trace[1544507202] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1220; }","duration":"132.433164ms","start":"2026-05-20T03:12:01.58529Z","end":"2026-05-20T03:12:01.717723Z","steps":["trace[1544507202] 'range keys from in-memory index tree' (duration: 132.116127ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:01.717798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.203039ms","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-05-20T03:12:01.717834Z","caller":"traceutil/trace.go:171","msg":"trace[1665153212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1220; }","duration":"133.280661ms","start":"2026-05-20T03:12:01.584545Z","end":"2026-05-20T03:12:01.717825Z","steps":["trace[1665153212] 'range keys from in-memory index tree' (duration: 133.062526ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:02.026896Z","caller":"traceutil/trace.go:171","msg":"trace[214385914] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"305.917022ms","start":"2026-05-20T03:12:01.720959Z","end":"2026-05-20T03:12:02.026876Z","steps":["trace[214385914] 'process raft request' (duration: 305.778259ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:02.027055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:01.720942Z","time spent":"306.022785ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:02.052185Z","caller":"traceutil/trace.go:171","msg":"trace[9422094] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1265; }","duration":"177.08099ms","start":"2026-05-20T03:12:01.875076Z","end":"2026-05-20T03:12:02.052157Z","steps":["trace[9422094] 'read index received' (duration: 151.760098ms)","trace[9422094] 'applied index is now lower than readState.Index' (duration: 25.319422ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:02.052251Z","caller":"traceutil/trace.go:171","msg":"trace[1006720248] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"331.163883ms","start":"2026-05-20T03:12:01.721056Z","end":"2026-05-20T03:12:02.05222Z","steps":["trace[1006720248] 'process raft request' (duration: 330.884938ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:02.052283Z","caller":"traceutil/trace.go:171","msg":"trace[1864312826] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"283.396946ms","start":"2026-05-20T03:12:01.768858Z","end":"2026-05-20T03:12:02.052255Z","steps":["trace[1864312826] 'process raft request' (duration: 283.242523ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:02.052334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.239494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:12:02.052374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:01.721032Z","time spent":"331.267636ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:02.05239Z","caller":"traceutil/trace.go:171","msg":"trace[898677289] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1223; }","duration":"177.345166ms","start":"2026-05-20T03:12:01.875025Z","end":"2026-05-20T03:12:02.052371Z","steps":["trace[898677289] 'agreement among raft nodes before linearized reading' (duration: 177.244994ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:02.802761Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920524,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:12:03.303403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920524,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:12:03.531544Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.473747371s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-20T03:12:04.376102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000238828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-05-20T03:12:04.376207Z","caller":"traceutil/trace.go:171","msg":"trace[1513953099] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000406162s","start":"2026-05-20T03:12:02.375781Z","end":"2026-05-20T03:12:04.376187Z","steps":["trace[1513953099] 'agreement among raft nodes before linearized reading' (duration: 2.000269819s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:04.376259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:02.37576Z","time spent":"2.000488523s","remote":"127.0.0.1:45252","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-05-20T03:12:04.7988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.966528214s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:04.798924Z","caller":"traceutil/trace.go:171","msg":"trace[187936019] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1223; }","duration":"1.966664836s","start":"2026-05-20T03:12:02.832242Z","end":"2026-05-20T03:12:04.798907Z","steps":["trace[187936019] 'range keys from in-memory index tree' (duration: 1.966510593s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:04.799175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.267164808s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:04.799429Z","caller":"traceutil/trace.go:171","msg":"trace[331752756] linearizableReadLoop","detail":"{readStateIndex:1270; appliedIndex:1268; }","duration":"2.497147004s","start":"2026-05-20T03:12:02.302263Z","end":"2026-05-20T03:12:04.79941Z","steps":["trace[331752756] 'read index received' (duration: 1.229472105s)","trace[331752756] 'applied index is now lower than readState.Index' (duration: 1.267673839s)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:04.799472Z","caller":"traceutil/trace.go:171","msg":"trace[1681555837] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"2.291488997s","start":"2026-05-20T03:12:02.507959Z","end":"2026-05-20T03:12:04.799448Z","steps":["trace[1681555837] 'process raft request' (duration: 2.291368495s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:04.799492Z","caller":"traceutil/trace.go:171","msg":"trace[1836838538] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"2.741722545s","start":"2026-05-20T03:12:02.057724Z","end":"2026-05-20T03:12:04.799446Z","steps":["trace[1836838538] 'process raft request' (duration: 1.473999826s)","trace[1836838538] 'compare' (duration: 1.266564547s)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:04.799578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:02.507933Z","time spent":"2.29159178s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:04.799596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.497331647s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:04.799981Z","caller":"traceutil/trace.go:171","msg":"trace[2068660139] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:1225; }","duration":"2.497751685s","start":"2026-05-20T03:12:02.302209Z","end":"2026-05-20T03:12:04.79996Z","steps":["trace[2068660139] 'agreement among raft nodes before linearized reading' (duration: 2.497318707s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:04.800041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:02.302189Z","time spent":"2.497835836s","remote":"127.0.0.1:43392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:12:04.799602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:02.057709Z","time spent":"2.741836177s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:05.078596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.278606ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-05-20T03:12:05.078712Z","caller":"traceutil/trace.go:171","msg":"trace[1609589054] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1270; }","duration":"279.199084ms","start":"2026-05-20T03:12:04.799496Z","end":"2026-05-20T03:12:05.078695Z","steps":["trace[1609589054] 'read index received' (duration: 157.788915ms)","trace[1609589054] 'applied index is now lower than readState.Index' (duration: 121.408789ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:05.078777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.562581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:12:05.078806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574078649s","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-05-20T03:12:05.078842Z","caller":"traceutil/trace.go:171","msg":"trace[2082215114] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1225; }","duration":"365.686484ms","start":"2026-05-20T03:12:04.713142Z","end":"2026-05-20T03:12:05.078828Z","steps":["trace[2082215114] 'agreement among raft nodes before linearized reading' (duration: 365.554272ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:05.078848Z","caller":"traceutil/trace.go:171","msg":"trace[693075369] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1225; }","duration":"1.574155521s","start":"2026-05-20T03:12:03.50468Z","end":"2026-05-20T03:12:05.078836Z","steps":["trace[693075369] 'agreement among raft nodes before linearized reading' (duration: 1.57407622s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.078885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.713121Z","time spent":"365.753785ms","remote":"127.0.0.1:43376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:12:05.078892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:03.504661Z","time spent":"1.574220912s","remote":"127.0.0.1:45426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:12:05.078889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.268765169s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-20T03:12:05.078965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"950.646998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:05.079062Z","caller":"traceutil/trace.go:171","msg":"trace[1761785468] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1225; }","duration":"950.786801ms","start":"2026-05-20T03:12:04.128255Z","end":"2026-05-20T03:12:05.079042Z","steps":["trace[1761785468] 'agreement among raft nodes before linearized reading' (duration: 950.609058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.12824Z","time spent":"950.854332ms","remote":"127.0.0.1:43570","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-05-20T03:12:05.079116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.603005891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:05.079146Z","caller":"traceutil/trace.go:171","msg":"trace[96840913] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:0; response_revision:1225; }","duration":"1.603075523s","start":"2026-05-20T03:12:03.476062Z","end":"2026-05-20T03:12:05.079138Z","steps":["trace[96840913] 'agreement among raft nodes before linearized reading' (duration: 1.603027302s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:03.476042Z","time spent":"1.603128734s","remote":"127.0.0.1:45442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"warn","ts":"2026-05-20T03:12:05.079181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022818499s","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-05-20T03:12:05.079217Z","caller":"traceutil/trace.go:171","msg":"trace[1439515148] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1225; }","duration":"1.022880641s","start":"2026-05-20T03:12:04.056327Z","end":"2026-05-20T03:12:05.079208Z","steps":["trace[1439515148] 'agreement among raft nodes before linearized reading' (duration: 1.022711728s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.048330856s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:12:05.079262Z","caller":"traceutil/trace.go:171","msg":"trace[311368822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1225; }","duration":"1.048356036s","start":"2026-05-20T03:12:04.030899Z","end":"2026-05-20T03:12:05.079256Z","steps":["trace[311368822] 'agreement among raft nodes before linearized reading' (duration: 1.048254364s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.205601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:12:05.079285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.030836Z","time spent":"1.048444487s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:12:05.079265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.056312Z","time spent":"1.022941702s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T03:12:05.078977Z","caller":"traceutil/trace.go:171","msg":"trace[1831278477] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1225; }","duration":"1.26889081s","start":"2026-05-20T03:12:03.810064Z","end":"2026-05-20T03:12:05.078955Z","steps":["trace[1831278477] 'agreement among raft nodes before linearized reading' (duration: 1.268697187s)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:05.079324Z","caller":"traceutil/trace.go:171","msg":"trace[225708050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1225; }","duration":"702.359444ms","start":"2026-05-20T03:12:04.376943Z","end":"2026-05-20T03:12:05.079303Z","steps":["trace[225708050] 'agreement among raft nodes before linearized reading' (duration: 702.15851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.545258926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:12:05.079526Z","caller":"traceutil/trace.go:171","msg":"trace[1822471792] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1225; }","duration":"2.545486482s","start":"2026-05-20T03:12:02.534021Z","end":"2026-05-20T03:12:05.079508Z","steps":["trace[1822471792] 'agreement among raft nodes before linearized reading' (duration: 2.545174815s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.845570464s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-20T03:12:05.079353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:03.810047Z","time spent":"1.269296768s","remote":"127.0.0.1:45610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:12:05.079618Z","caller":"traceutil/trace.go:171","msg":"trace[304849568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1225; }","duration":"1.845629955s","start":"2026-05-20T03:12:03.233981Z","end":"2026-05-20T03:12:05.079611Z","steps":["trace[304849568] 'agreement among raft nodes before linearized reading' (duration: 1.845506112s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.079376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.376913Z","time spent":"702.451537ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:05.079645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:03.233963Z","time spent":"1.845677907s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-20T03:12:05.079596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:02.534005Z","time spent":"2.545574463s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:12:05.301531Z","caller":"traceutil/trace.go:171","msg":"trace[63308038] linearizableReadLoop","detail":"{readStateIndex:1272; appliedIndex:1271; }","duration":"222.769868ms","start":"2026-05-20T03:12:05.078735Z","end":"2026-05-20T03:12:05.301505Z","steps":["trace[63308038] 'read index received' (duration: 222.544554ms)","trace[63308038] 'applied index is now lower than readState.Index' (duration: 224.444µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:05.301682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.56445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:05.301623Z","caller":"traceutil/trace.go:171","msg":"trace[623238420] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"223.965022ms","start":"2026-05-20T03:12:05.077623Z","end":"2026-05-20T03:12:05.301588Z","steps":["trace[623238420] 'process raft request' (duration: 223.729987ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:05.301761Z","caller":"traceutil/trace.go:171","msg":"trace[578577079] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"404.689493ms","start":"2026-05-20T03:12:04.897053Z","end":"2026-05-20T03:12:05.301742Z","steps":["trace[578577079] 'agreement among raft nodes before linearized reading' (duration: 404.55389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.3018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:04.897031Z","time spent":"404.761443ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:05.450646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.639971ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:05.450947Z","caller":"traceutil/trace.go:171","msg":"trace[473418248] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1272; }","duration":"149.220809ms","start":"2026-05-20T03:12:05.30171Z","end":"2026-05-20T03:12:05.450931Z","steps":["trace[473418248] 'read index received' (duration: 41.16512ms)","trace[473418248] 'applied index is now lower than readState.Index' (duration: 108.054679ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:05.451047Z","caller":"traceutil/trace.go:171","msg":"trace[1137133754] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"367.268295ms","start":"2026-05-20T03:12:05.083751Z","end":"2026-05-20T03:12:05.45102Z","steps":["trace[1137133754] 'process raft request' (duration: 259.178085ms)","trace[1137133754] 'compare' (duration: 107.487009ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:05.451089Z","caller":"traceutil/trace.go:171","msg":"trace[1996951595] transaction","detail":"{read_only:false; response_revision:1229; number_of_response:1; }","duration":"366.01876ms","start":"2026-05-20T03:12:05.085059Z","end":"2026-05-20T03:12:05.451078Z","steps":["trace[1996951595] 'process raft request' (duration: 365.771175ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.451146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.083733Z","time spent":"367.367486ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:12:05.45116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.085046Z","time spent":"366.067032ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:05.451182Z","caller":"traceutil/trace.go:171","msg":"trace[879353621] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"366.547861ms","start":"2026-05-20T03:12:05.084431Z","end":"2026-05-20T03:12:05.450979Z","steps":["trace[879353621] 'process raft request' (duration: 366.315207ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.451431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.380157ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:05.451419Z","caller":"traceutil/trace.go:171","msg":"trace[418406180] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"366.295136ms","start":"2026-05-20T03:12:05.085091Z","end":"2026-05-20T03:12:05.451386Z","steps":["trace[418406180] 'process raft request' (duration: 365.777076ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:05.451492Z","caller":"traceutil/trace.go:171","msg":"trace[1119201660] range","detail":"{range_begin:/registry/limitranges/default/; range_end:/registry/limitranges/default0; response_count:0; response_revision:1231; }","duration":"366.480339ms","start":"2026-05-20T03:12:05.084995Z","end":"2026-05-20T03:12:05.451475Z","steps":["trace[1119201660] 'agreement among raft nodes before linearized reading' (duration: 366.344517ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.451544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.08498Z","time spent":"366.55271ms","remote":"127.0.0.1:45414","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/default/\" range_end:\"/registry/limitranges/default0\" "} {"level":"warn","ts":"2026-05-20T03:12:05.451578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.085079Z","time spent":"366.415198ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:05.451429Z","caller":"traceutil/trace.go:171","msg":"trace[1055048915] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"364.192275ms","start":"2026-05-20T03:12:05.087215Z","end":"2026-05-20T03:12:05.451407Z","steps":["trace[1055048915] 'process raft request' (duration: 363.680356ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:05.451872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.087206Z","time spent":"364.537061ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:05.451774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.084421Z","time spent":"366.928618ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:05.681513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.105989ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:05.681705Z","caller":"traceutil/trace.go:171","msg":"trace[2119361488] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"219.141757ms","start":"2026-05-20T03:12:05.462547Z","end":"2026-05-20T03:12:05.681689Z","steps":["trace[2119361488] 'process raft request' (duration: 219.053056ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:05.682145Z","caller":"traceutil/trace.go:171","msg":"trace[1045801279] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"223.823099ms","start":"2026-05-20T03:12:05.45831Z","end":"2026-05-20T03:12:05.682133Z","steps":["trace[1045801279] 'process raft request' (duration: 96.035367ms)","trace[1045801279] 'compare' (duration: 126.858143ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:06.027295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.887032ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:06.027391Z","caller":"traceutil/trace.go:171","msg":"trace[802083246] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"334.191372ms","start":"2026-05-20T03:12:05.693182Z","end":"2026-05-20T03:12:06.027373Z","steps":["trace[802083246] 'process raft request' (duration: 168.097725ms)","trace[802083246] 'compare' (duration: 165.79093ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:06.027452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:05.693154Z","time spent":"334.267824ms","remote":"127.0.0.1:45442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:07.248489Z","caller":"traceutil/trace.go:171","msg":"trace[1833683187] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"607.968372ms","start":"2026-05-20T03:12:06.640495Z","end":"2026-05-20T03:12:07.248464Z","steps":["trace[1833683187] 'process raft request' (duration: 607.85921ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.248785Z","caller":"traceutil/trace.go:171","msg":"trace[1592818397] linearizableReadLoop","detail":"{readStateIndex:1282; appliedIndex:1282; }","duration":"443.945735ms","start":"2026-05-20T03:12:06.804806Z","end":"2026-05-20T03:12:07.248751Z","steps":["trace[1592818397] 'read index received' (duration: 443.934645ms)","trace[1592818397] 'applied index is now lower than readState.Index' (duration: 8.47µs)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:07.248869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:06.640477Z","time spent":"608.329059ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1475,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-20T03:12:07.249099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.268991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-20T03:12:07.249159Z","caller":"traceutil/trace.go:171","msg":"trace[131908944] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"444.378362ms","start":"2026-05-20T03:12:06.804766Z","end":"2026-05-20T03:12:07.249144Z","steps":["trace[131908944] 'agreement among raft nodes before linearized reading' (duration: 444.127768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.249217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:06.804747Z","time spent":"444.453174ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-20T03:12:07.300204Z","caller":"traceutil/trace.go:171","msg":"trace[2064320930] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"492.817545ms","start":"2026-05-20T03:12:06.807363Z","end":"2026-05-20T03:12:07.300181Z","steps":["trace[2064320930] 'process raft request' (duration: 492.673462ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.30051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:06.807326Z","time spent":"493.10422ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:07.656204Z","caller":"traceutil/trace.go:171","msg":"trace[1495693416] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"402.688843ms","start":"2026-05-20T03:12:07.253482Z","end":"2026-05-20T03:12:07.656171Z","steps":["trace[1495693416] 'process raft request' (duration: 402.384657ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.656277Z","caller":"traceutil/trace.go:171","msg":"trace[1118666030] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"402.724334ms","start":"2026-05-20T03:12:07.25352Z","end":"2026-05-20T03:12:07.656245Z","steps":["trace[1118666030] 'process raft request' (duration: 402.518561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.656317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.445519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1948"} {"level":"warn","ts":"2026-05-20T03:12:07.656345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:07.253458Z","time spent":"402.825756ms","remote":"127.0.0.1:45352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":767,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-20T03:12:07.656378Z","caller":"traceutil/trace.go:171","msg":"trace[973881267] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1240; }","duration":"401.547161ms","start":"2026-05-20T03:12:07.254821Z","end":"2026-05-20T03:12:07.656368Z","steps":["trace[973881267] 'agreement among raft nodes before linearized reading' (duration: 401.429859ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.656419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:07.254805Z","time spent":"401.606263ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1970,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-05-20T03:12:07.656435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:07.253494Z","time spent":"402.856086ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:07.6562Z","caller":"traceutil/trace.go:171","msg":"trace[1361342273] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1282; }","duration":"401.320407ms","start":"2026-05-20T03:12:07.254863Z","end":"2026-05-20T03:12:07.656183Z","steps":["trace[1361342273] 'read index received' (duration: 45.22942ms)","trace[1361342273] 'applied index is now lower than readState.Index' (duration: 356.090057ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:07.656599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.769644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:07.656632Z","caller":"traceutil/trace.go:171","msg":"trace[1798815751] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1240; }","duration":"280.855996ms","start":"2026-05-20T03:12:07.375767Z","end":"2026-05-20T03:12:07.656623Z","steps":["trace[1798815751] 'agreement among raft nodes before linearized reading' (duration: 280.799275ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.65672Z","caller":"traceutil/trace.go:171","msg":"trace[1096046142] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"401.434149ms","start":"2026-05-20T03:12:07.255074Z","end":"2026-05-20T03:12:07.656508Z","steps":["trace[1096046142] 'process raft request' (duration: 401.051102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.656858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.679489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-20T03:12:07.656892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.942023ms","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-05-20T03:12:07.656906Z","caller":"traceutil/trace.go:171","msg":"trace[1069730210] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1240; }","duration":"200.76456ms","start":"2026-05-20T03:12:07.456129Z","end":"2026-05-20T03:12:07.656894Z","steps":["trace[1069730210] 'agreement among raft nodes before linearized reading' (duration: 200.614727ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.656902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.953193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-20T03:12:07.656942Z","caller":"traceutil/trace.go:171","msg":"trace[130083093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1240; }","duration":"201.033685ms","start":"2026-05-20T03:12:07.455894Z","end":"2026-05-20T03:12:07.656928Z","steps":["trace[130083093] 'agreement among raft nodes before linearized reading' (duration: 200.862442ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.65696Z","caller":"traceutil/trace.go:171","msg":"trace[1852568807] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1240; }","duration":"201.036075ms","start":"2026-05-20T03:12:07.455908Z","end":"2026-05-20T03:12:07.656944Z","steps":["trace[1852568807] 'agreement among raft nodes before linearized reading' (duration: 200.845171ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.656966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.677828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-20T03:12:07.657008Z","caller":"traceutil/trace.go:171","msg":"trace[486817705] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1240; }","duration":"200.749811ms","start":"2026-05-20T03:12:07.456244Z","end":"2026-05-20T03:12:07.656994Z","steps":["trace[486817705] 'agreement among raft nodes before linearized reading' (duration: 200.650698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:07.657103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:07.255064Z","time spent":"401.770346ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1933,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:07.947605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.912984ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:07.947796Z","caller":"traceutil/trace.go:171","msg":"trace[1984398245] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"286.659259ms","start":"2026-05-20T03:12:07.661121Z","end":"2026-05-20T03:12:07.94778Z","steps":["trace[1984398245] 'process raft request' (duration: 286.572237ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.94794Z","caller":"traceutil/trace.go:171","msg":"trace[334147922] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"287.148188ms","start":"2026-05-20T03:12:07.660717Z","end":"2026-05-20T03:12:07.947865Z","steps":["trace[334147922] 'process raft request' (duration: 123.907908ms)","trace[334147922] 'compare' (duration: 162.821283ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:07.948083Z","caller":"traceutil/trace.go:171","msg":"trace[1237347658] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"286.879793ms","start":"2026-05-20T03:12:07.661182Z","end":"2026-05-20T03:12:07.948062Z","steps":["trace[1237347658] 'process raft request' (duration: 286.568257ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:07.948271Z","caller":"traceutil/trace.go:171","msg":"trace[802872030] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"286.66733ms","start":"2026-05-20T03:12:07.661585Z","end":"2026-05-20T03:12:07.948252Z","steps":["trace[802872030] 'process raft request' (duration: 286.371603ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:08.524961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.422894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.129\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-20T03:12:08.525052Z","caller":"traceutil/trace.go:171","msg":"trace[848195814] range","detail":"{range_begin:/registry/masterleases/199.204.45.129; range_end:; response_count:1; response_revision:1244; }","duration":"199.571997ms","start":"2026-05-20T03:12:08.32546Z","end":"2026-05-20T03:12:08.525032Z","steps":["trace[848195814] 'range keys from in-memory index tree' (duration: 199.20582ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:09.87505Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15276102473207920565,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-20T03:12:10.146634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.331993787s","expected-duration":"1s"} {"level":"info","ts":"2026-05-20T03:12:10.147101Z","caller":"traceutil/trace.go:171","msg":"trace[1073547394] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"1.332543187s","start":"2026-05-20T03:12:08.814529Z","end":"2026-05-20T03:12:10.147073Z","steps":["trace[1073547394] 'process raft request' (duration: 1.332331763s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.147386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:08.8145Z","time spent":"1.33267333s","remote":"127.0.0.1:45296","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:10.281704Z","caller":"traceutil/trace.go:171","msg":"trace[2002905769] linearizableReadLoop","detail":"{readStateIndex:1293; appliedIndex:1291; }","duration":"906.696594ms","start":"2026-05-20T03:12:09.374983Z","end":"2026-05-20T03:12:10.281679Z","steps":["trace[2002905769] 'read index received' (duration: 771.878805ms)","trace[2002905769] 'applied index is now lower than readState.Index' (duration: 134.816679ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:10.281966Z","caller":"traceutil/trace.go:171","msg":"trace[749163115] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"976.828266ms","start":"2026-05-20T03:12:09.305119Z","end":"2026-05-20T03:12:10.281948Z","steps":["trace[749163115] 'process raft request' (duration: 975.98598ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.28201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"907.02213ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:10.282077Z","caller":"traceutil/trace.go:171","msg":"trace[408498635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"907.125903ms","start":"2026-05-20T03:12:09.374936Z","end":"2026-05-20T03:12:10.282062Z","steps":["trace[408498635] 'agreement among raft nodes before linearized reading' (duration: 907.017961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.374918Z","time spent":"907.190514ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:10.282111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.305095Z","time spent":"976.928889ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:10.282184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"619.420823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:12:10.282297Z","caller":"traceutil/trace.go:171","msg":"trace[1362967290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1246; }","duration":"619.604257ms","start":"2026-05-20T03:12:09.662674Z","end":"2026-05-20T03:12:10.282278Z","steps":["trace[1362967290] 'agreement among raft nodes before linearized reading' (duration: 619.293741ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.662603Z","time spent":"619.72946ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:12:10.282635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.491111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:12:10.282673Z","caller":"traceutil/trace.go:171","msg":"trace[1473118373] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1246; }","duration":"330.530482ms","start":"2026-05-20T03:12:09.952132Z","end":"2026-05-20T03:12:10.282662Z","steps":["trace[1473118373] 'agreement among raft nodes before linearized reading' (duration: 330.355858ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.559082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-20T03:12:10.282703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.952113Z","time spent":"330.582532ms","remote":"127.0.0.1:45522","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-05-20T03:12:10.282714Z","caller":"traceutil/trace.go:171","msg":"trace[707263966] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1246; }","duration":"330.668953ms","start":"2026-05-20T03:12:09.952031Z","end":"2026-05-20T03:12:10.2827Z","steps":["trace[707263966] 'agreement among raft nodes before linearized reading' (duration: 330.47267ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.95201Z","time spent":"330.729575ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-20T03:12:10.282799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.255608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-05-20T03:12:10.282824Z","caller":"traceutil/trace.go:171","msg":"trace[1323840581] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1246; }","duration":"133.300489ms","start":"2026-05-20T03:12:10.149514Z","end":"2026-05-20T03:12:10.282815Z","steps":["trace[1323840581] 'agreement among raft nodes before linearized reading' (duration: 133.250058ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.824738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:12:10.282925Z","caller":"traceutil/trace.go:171","msg":"trace[97610648] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1246; }","duration":"329.873048ms","start":"2026-05-20T03:12:09.953044Z","end":"2026-05-20T03:12:10.282917Z","steps":["trace[97610648] 'agreement among raft nodes before linearized reading' (duration: 329.778977ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.282955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.953031Z","time spent":"329.91803ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-20T03:12:10.283198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.079741ms","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-05-20T03:12:10.283246Z","caller":"traceutil/trace.go:171","msg":"trace[920294630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1246; }","duration":"331.142152ms","start":"2026-05-20T03:12:09.952092Z","end":"2026-05-20T03:12:10.283234Z","steps":["trace[920294630] 'agreement among raft nodes before linearized reading' (duration: 331.02692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:10.28328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:09.952087Z","time spent":"331.186493ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T03:12:20.435582Z","caller":"traceutil/trace.go:171","msg":"trace[298924885] transaction","detail":"{read_only:false; response_revision:1332; number_of_response:1; }","duration":"108.704364ms","start":"2026-05-20T03:12:20.32686Z","end":"2026-05-20T03:12:20.435564Z","steps":["trace[298924885] 'process raft request' (duration: 108.422709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:26.419082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.777203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:26.419172Z","caller":"traceutil/trace.go:171","msg":"trace[1673971841] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1381; }","duration":"154.887595ms","start":"2026-05-20T03:12:26.264259Z","end":"2026-05-20T03:12:26.419147Z","steps":["trace[1673971841] 'count revisions from in-memory index tree' (duration: 154.6166ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:27.650135Z","caller":"traceutil/trace.go:171","msg":"trace[302871200] transaction","detail":"{read_only:false; response_revision:1390; number_of_response:1; }","duration":"108.355958ms","start":"2026-05-20T03:12:27.541728Z","end":"2026-05-20T03:12:27.650084Z","steps":["trace[302871200] 'process raft request' (duration: 108.140714ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:30.901615Z","caller":"traceutil/trace.go:171","msg":"trace[1829587734] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"256.116825ms","start":"2026-05-20T03:12:30.645469Z","end":"2026-05-20T03:12:30.901586Z","steps":["trace[1829587734] 'read index received' (duration: 255.8657ms)","trace[1829587734] 'applied index is now lower than readState.Index' (duration: 249.645µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:30.90174Z","caller":"traceutil/trace.go:171","msg":"trace[1523105905] transaction","detail":"{read_only:false; response_revision:1404; number_of_response:1; }","duration":"374.529956ms","start":"2026-05-20T03:12:30.527173Z","end":"2026-05-20T03:12:30.901703Z","steps":["trace[1523105905] 'process raft request' (duration: 374.240861ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:30.901981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.841901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-20T03:12:30.902016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.761828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-20T03:12:30.90203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.911693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:12:30.902075Z","caller":"traceutil/trace.go:171","msg":"trace[2018764684] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1404; }","duration":"240.873163ms","start":"2026-05-20T03:12:30.661187Z","end":"2026-05-20T03:12:30.90206Z","steps":["trace[2018764684] 'agreement among raft nodes before linearized reading' (duration: 240.743879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:30.902049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.757839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:12:30.902091Z","caller":"traceutil/trace.go:171","msg":"trace[1779475718] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1404; }","duration":"240.031386ms","start":"2026-05-20T03:12:30.662044Z","end":"2026-05-20T03:12:30.902076Z","steps":["trace[1779475718] 'agreement among raft nodes before linearized reading' (duration: 239.787861ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:30.902101Z","caller":"traceutil/trace.go:171","msg":"trace[1449755927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1404; }","duration":"240.966144ms","start":"2026-05-20T03:12:30.661086Z","end":"2026-05-20T03:12:30.902052Z","steps":["trace[1449755927] 'agreement among raft nodes before linearized reading' (duration: 240.693188ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:30.902127Z","caller":"traceutil/trace.go:171","msg":"trace[1164292569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1404; }","duration":"240.884342ms","start":"2026-05-20T03:12:30.661227Z","end":"2026-05-20T03:12:30.902112Z","steps":["trace[1164292569] 'agreement among raft nodes before linearized reading' (duration: 240.692487ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:30.90213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:30.527148Z","time spent":"374.68617ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:30.90246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.986553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-20T03:12:30.902532Z","caller":"traceutil/trace.go:171","msg":"trace[1439820510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1404; }","duration":"257.097204ms","start":"2026-05-20T03:12:30.645419Z","end":"2026-05-20T03:12:30.902516Z","steps":["trace[1439820510] 'agreement among raft nodes before linearized reading' (duration: 256.300718ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:30.902751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.922228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-20T03:12:30.902842Z","caller":"traceutil/trace.go:171","msg":"trace[1869514137] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1404; }","duration":"206.102121ms","start":"2026-05-20T03:12:30.696723Z","end":"2026-05-20T03:12:30.902825Z","steps":["trace[1869514137] 'agreement among raft nodes before linearized reading' (duration: 205.123332ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:31.193331Z","caller":"traceutil/trace.go:171","msg":"trace[622240494] transaction","detail":"{read_only:false; response_revision:1411; number_of_response:1; }","duration":"118.901481ms","start":"2026-05-20T03:12:31.074403Z","end":"2026-05-20T03:12:31.193305Z","steps":["trace[622240494] 'process raft request' (duration: 118.495013ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:31.621171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.265116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:12:31.621192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.219979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-05-20T03:12:31.621247Z","caller":"traceutil/trace.go:171","msg":"trace[1414496486] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1411; }","duration":"245.397119ms","start":"2026-05-20T03:12:31.375832Z","end":"2026-05-20T03:12:31.621229Z","steps":["trace[1414496486] 'range keys from in-memory index tree' (duration: 245.155394ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:31.621302Z","caller":"traceutil/trace.go:171","msg":"trace[177575166] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1411; }","duration":"145.415612ms","start":"2026-05-20T03:12:31.475859Z","end":"2026-05-20T03:12:31.621274Z","steps":["trace[177575166] 'range keys from in-memory index tree' (duration: 144.974794ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:32.280665Z","caller":"traceutil/trace.go:171","msg":"trace[144512307] linearizableReadLoop","detail":"{readStateIndex:1463; appliedIndex:1462; }","duration":"173.753328ms","start":"2026-05-20T03:12:32.106888Z","end":"2026-05-20T03:12:32.280641Z","steps":["trace[144512307] 'read index received' (duration: 173.607246ms)","trace[144512307] 'applied index is now lower than readState.Index' (duration: 144.732µs)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:32.280742Z","caller":"traceutil/trace.go:171","msg":"trace[204702365] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"654.866009ms","start":"2026-05-20T03:12:31.625818Z","end":"2026-05-20T03:12:32.280684Z","steps":["trace[204702365] 'process raft request' (duration: 654.639965ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:32.280877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.962442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-05-20T03:12:32.280958Z","caller":"traceutil/trace.go:171","msg":"trace[972714192] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1412; }","duration":"174.099945ms","start":"2026-05-20T03:12:32.106846Z","end":"2026-05-20T03:12:32.280946Z","steps":["trace[972714192] 'agreement among raft nodes before linearized reading' (duration: 173.896761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:32.281062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:31.625792Z","time spent":"655.084514ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:33.202931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.560851ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-05-20T03:12:33.203127Z","caller":"traceutil/trace.go:171","msg":"trace[583034844] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1412; }","duration":"547.824826ms","start":"2026-05-20T03:12:32.655271Z","end":"2026-05-20T03:12:33.203096Z","steps":["trace[583034844] 'range keys from in-memory index tree' (duration: 547.274845ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.203196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.655253Z","time spent":"547.929897ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-20T03:12:33.203509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.163238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:33.203593Z","caller":"traceutil/trace.go:171","msg":"trace[1996480604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"828.24152ms","start":"2026-05-20T03:12:32.375329Z","end":"2026-05-20T03:12:33.20357Z","steps":["trace[1996480604] 'range keys from in-memory index tree' (duration: 828.078716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.203644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.375295Z","time spent":"828.340311ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:33.202946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.243105ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:33.20409Z","caller":"traceutil/trace.go:171","msg":"trace[844183543] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1412; }","duration":"371.415478ms","start":"2026-05-20T03:12:32.832656Z","end":"2026-05-20T03:12:33.204071Z","steps":["trace[844183543] 'range keys from in-memory index tree' (duration: 370.221005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.204122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"499.904523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2813"} {"level":"warn","ts":"2026-05-20T03:12:33.204184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.965057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-20T03:12:33.204231Z","caller":"traceutil/trace.go:171","msg":"trace[748186539] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1413; }","duration":"278.052938ms","start":"2026-05-20T03:12:32.926167Z","end":"2026-05-20T03:12:33.20422Z","steps":["trace[748186539] 'agreement among raft nodes before linearized reading' (duration: 277.876724ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:33.204228Z","caller":"traceutil/trace.go:171","msg":"trace[1008150813] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1412; }","duration":"500.070915ms","start":"2026-05-20T03:12:32.704137Z","end":"2026-05-20T03:12:33.204208Z","steps":["trace[1008150813] 'range keys from in-memory index tree' (duration: 498.665448ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.204294Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.704118Z","time spent":"500.166798ms","remote":"127.0.0.1:45468","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2835,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-05-20T03:12:33.204345Z","caller":"traceutil/trace.go:171","msg":"trace[1786183488] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"295.842211ms","start":"2026-05-20T03:12:32.908489Z","end":"2026-05-20T03:12:33.204331Z","steps":["trace[1786183488] 'process raft request' (duration: 242.070104ms)","trace[1786183488] 'compare' (duration: 52.461061ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:33.204012Z","caller":"traceutil/trace.go:171","msg":"trace[1169864346] linearizableReadLoop","detail":"{readStateIndex:1464; appliedIndex:1463; }","duration":"277.773652ms","start":"2026-05-20T03:12:32.926222Z","end":"2026-05-20T03:12:33.203995Z","steps":["trace[1169864346] 'read index received' (duration: 224.348752ms)","trace[1169864346] 'applied index is now lower than readState.Index' (duration: 53.42357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:33.95411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.286367ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-20T03:12:33.95419Z","caller":"traceutil/trace.go:171","msg":"trace[97373084] linearizableReadLoop","detail":"{readStateIndex:1465; appliedIndex:1464; }","duration":"749.556894ms","start":"2026-05-20T03:12:33.204621Z","end":"2026-05-20T03:12:33.954178Z","steps":["trace[97373084] 'read index received' (duration: 367.257787ms)","trace[97373084] 'applied index is now lower than readState.Index' (duration: 382.298357ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:33.954299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.017100189s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-20T03:12:33.954316Z","caller":"traceutil/trace.go:171","msg":"trace[110176682] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1414; }","duration":"1.017212262s","start":"2026-05-20T03:12:32.937098Z","end":"2026-05-20T03:12:33.954311Z","steps":["trace[110176682] 'agreement among raft nodes before linearized reading' (duration: 1.017110139s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.954338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.937084Z","time spent":"1.017248333s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-20T03:12:33.95453Z","caller":"traceutil/trace.go:171","msg":"trace[1113622307] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"1.006439214s","start":"2026-05-20T03:12:32.94808Z","end":"2026-05-20T03:12:33.954519Z","steps":["trace[1113622307] 'process raft request' (duration: 623.670228ms)","trace[1113622307] 'compare' (duration: 381.95607ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:33.954582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.948059Z","time spent":"1.006494215s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:33.954708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.130015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:33.954733Z","caller":"traceutil/trace.go:171","msg":"trace[185529881] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1414; }","duration":"750.199017ms","start":"2026-05-20T03:12:33.204527Z","end":"2026-05-20T03:12:33.954726Z","steps":["trace[185529881] 'agreement among raft nodes before linearized reading' (duration: 750.154876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.954758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.204505Z","time spent":"750.247407ms","remote":"127.0.0.1:45250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:33.95484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016333215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-20T03:12:33.954874Z","caller":"traceutil/trace.go:171","msg":"trace[1250151836] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1414; }","duration":"1.016392176s","start":"2026-05-20T03:12:32.938471Z","end":"2026-05-20T03:12:33.954863Z","steps":["trace[1250151836] 'agreement among raft nodes before linearized reading' (duration: 1.016294825s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.954859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.921727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-20T03:12:33.9549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.93846Z","time spent":"1.016432757s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-20T03:12:33.954909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016789593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-05-20T03:12:33.954926Z","caller":"traceutil/trace.go:171","msg":"trace[1361089433] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:1414; }","duration":"921.037178ms","start":"2026-05-20T03:12:33.033873Z","end":"2026-05-20T03:12:33.95491Z","steps":["trace[1361089433] 'agreement among raft nodes before linearized reading' (duration: 920.914776ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.954972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.033856Z","time spent":"921.108379ms","remote":"127.0.0.1:45544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":27,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"info","ts":"2026-05-20T03:12:33.954971Z","caller":"traceutil/trace.go:171","msg":"trace[1599786062] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1414; }","duration":"1.016894485s","start":"2026-05-20T03:12:32.938061Z","end":"2026-05-20T03:12:33.954955Z","steps":["trace[1599786062] 'agreement among raft nodes before linearized reading' (duration: 1.016747302s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.955034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016753992s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-20T03:12:33.955044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.93804Z","time spent":"1.016995238s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-05-20T03:12:33.955077Z","caller":"traceutil/trace.go:171","msg":"trace[1005770643] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1414; }","duration":"1.016810024s","start":"2026-05-20T03:12:32.938252Z","end":"2026-05-20T03:12:33.955062Z","steps":["trace[1005770643] 'agreement among raft nodes before linearized reading' (duration: 1.016700032s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.955113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.938242Z","time spent":"1.016859045s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-20T03:12:33.954909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.016751303s","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-05-20T03:12:33.955145Z","caller":"traceutil/trace.go:171","msg":"trace[22050311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1414; }","duration":"1.017020947s","start":"2026-05-20T03:12:32.938116Z","end":"2026-05-20T03:12:33.955137Z","steps":["trace[22050311] 'agreement among raft nodes before linearized reading' (duration: 1.01663464s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:33.955173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:32.938112Z","time spent":"1.017050118s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-20T03:12:34.336063Z","caller":"traceutil/trace.go:171","msg":"trace[1311891266] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"1.126552818s","start":"2026-05-20T03:12:33.209473Z","end":"2026-05-20T03:12:34.336026Z","steps":["trace[1311891266] 'process raft request' (duration: 1.126401585s)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.336304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.209449Z","time spent":"1.12672072s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:34.336354Z","caller":"traceutil/trace.go:171","msg":"trace[453555157] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"1.126838353s","start":"2026-05-20T03:12:33.209476Z","end":"2026-05-20T03:12:34.336315Z","steps":["trace[453555157] 'process raft request' (duration: 1.076274149s)","trace[453555157] 'compare' (duration: 49.769559ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:12:34.336609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.209448Z","time spent":"1.127043126s","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:34.34308Z","caller":"traceutil/trace.go:171","msg":"trace[1175253483] linearizableReadLoop","detail":"{readStateIndex:1474; appliedIndex:1465; }","duration":"379.151657ms","start":"2026-05-20T03:12:33.963916Z","end":"2026-05-20T03:12:34.343067Z","steps":["trace[1175253483] 'read index received' (duration: 321.920733ms)","trace[1175253483] 'applied index is now lower than readState.Index' (duration: 57.229944ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:12:34.343162Z","caller":"traceutil/trace.go:171","msg":"trace[1371723147] transaction","detail":"{read_only:false; response_revision:1422; number_of_response:1; }","duration":"382.046542ms","start":"2026-05-20T03:12:33.961108Z","end":"2026-05-20T03:12:34.343154Z","steps":["trace[1371723147] 'process raft request' (duration: 381.9333ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:34.343165Z","caller":"traceutil/trace.go:171","msg":"trace[1725492893] transaction","detail":"{read_only:false; response_revision:1418; number_of_response:1; }","duration":"384.849796ms","start":"2026-05-20T03:12:33.958282Z","end":"2026-05-20T03:12:34.343132Z","steps":["trace[1725492893] 'process raft request' (duration: 384.624612ms)"],"step_count":1} {"level":"info","ts":"2026-05-20T03:12:34.343216Z","caller":"traceutil/trace.go:171","msg":"trace[841196992] transaction","detail":"{read_only:false; response_revision:1419; number_of_response:1; }","duration":"384.488659ms","start":"2026-05-20T03:12:33.958701Z","end":"2026-05-20T03:12:34.34319Z","steps":["trace[841196992] 'process raft request' (duration: 384.240624ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.961103Z","time spent":"382.095203ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:34.343226Z","caller":"traceutil/trace.go:171","msg":"trace[125851899] transaction","detail":"{read_only:false; response_revision:1421; number_of_response:1; }","duration":"382.167995ms","start":"2026-05-20T03:12:33.961005Z","end":"2026-05-20T03:12:34.343173Z","steps":["trace[125851899] 'process raft request' (duration: 381.981371ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.958697Z","time spent":"384.5538ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:34.343313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.95827Z","time spent":"384.973059ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-20T03:12:34.343407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.491683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-20T03:12:34.343432Z","caller":"traceutil/trace.go:171","msg":"trace[1502207017] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1422; }","duration":"379.525653ms","start":"2026-05-20T03:12:33.963899Z","end":"2026-05-20T03:12:34.343425Z","steps":["trace[1502207017] 'agreement among raft nodes before linearized reading' (duration: 379.471062ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.963891Z","time spent":"379.555924ms","remote":"127.0.0.1:45252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-20T03:12:34.343462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.961Z","time spent":"382.321207ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:34.343608Z","caller":"traceutil/trace.go:171","msg":"trace[1353218112] transaction","detail":"{read_only:false; response_revision:1417; number_of_response:1; }","duration":"493.088143ms","start":"2026-05-20T03:12:33.850511Z","end":"2026-05-20T03:12:34.343599Z","steps":["trace[1353218112] 'process raft request' (duration: 492.314637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.458808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:534"} {"level":"info","ts":"2026-05-20T03:12:34.343647Z","caller":"traceutil/trace.go:171","msg":"trace[853117718] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1422; }","duration":"276.519209ms","start":"2026-05-20T03:12:34.06712Z","end":"2026-05-20T03:12:34.343639Z","steps":["trace[853117718] 'agreement among raft nodes before linearized reading' (duration: 276.432688ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.850461Z","time spent":"493.176944ms","remote":"127.0.0.1:45454","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7347,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:12:34.343743Z","caller":"traceutil/trace.go:171","msg":"trace[1051851795] transaction","detail":"{read_only:false; response_revision:1420; number_of_response:1; }","duration":"384.53825ms","start":"2026-05-20T03:12:33.959198Z","end":"2026-05-20T03:12:34.343736Z","steps":["trace[1051851795] 'process raft request' (duration: 383.767825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-20T03:12:34.343782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-20T03:12:33.959193Z","time spent":"384.569072ms","remote":"127.0.0.1:45522","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-20T03:13:07.551932Z","caller":"traceutil/trace.go:171","msg":"trace[2067389402] linearizableReadLoop","detail":"{readStateIndex:1640; appliedIndex:1639; }","duration":"108.172894ms","start":"2026-05-20T03:13:07.443725Z","end":"2026-05-20T03:13:07.551897Z","steps":["trace[2067389402] 'read index received' (duration: 24.019163ms)","trace[2067389402] 'applied index is now lower than readState.Index' (duration: 84.152101ms)"],"step_count":2} {"level":"info","ts":"2026-05-20T03:13:07.552107Z","caller":"traceutil/trace.go:171","msg":"trace[1870740539] transaction","detail":"{read_only:false; response_revision:1581; number_of_response:1; }","duration":"152.437737ms","start":"2026-05-20T03:13:07.399648Z","end":"2026-05-20T03:13:07.552086Z","steps":["trace[1870740539] 'process raft request' (duration: 68.095332ms)","trace[1870740539] 'compare' (duration: 83.945167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-20T03:13:07.552299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.552422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-20T03:13:07.552396Z","caller":"traceutil/trace.go:171","msg":"trace[1648721758] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1581; }","duration":"108.702135ms","start":"2026-05-20T03:13:07.443669Z","end":"2026-05-20T03:13:07.552371Z","steps":["trace[1648721758] 'agreement among raft nodes before linearized reading' (duration: 108.532862ms)"],"step_count":1}