{"level":"warn","ts":"2026-04-27T12:05:00.939639Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:05:00.939766Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.5:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.5:2380","--initial-cluster=instance=https://199.19.213.5:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.5:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.5: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-04-27T12:05:00.93985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-27T12:05:00.940963Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.5:2380"]} {"level":"info","ts":"2026-04-27T12:05:00.94099Z","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-04-27T12:05:00.943018Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"]} {"level":"info","ts":"2026-04-27T12:05:00.943137Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.5:2380"],"listen-peer-urls":["https://199.19.213.5:2380"],"advertise-client-urls":["https://199.19.213.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.5: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-04-27T12:05:00.952098Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.553098ms"} {"level":"info","ts":"2026-04-27T12:05:00.957726Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"dcb0af7a3afe1d6d","cluster-id":"4564295d4e5c9a3e"} {"level":"info","ts":"2026-04-27T12:05:00.957821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=()"} {"level":"info","ts":"2026-04-27T12:05:00.95785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became follower at term 0"} {"level":"info","ts":"2026-04-27T12:05:00.957871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft dcb0af7a3afe1d6d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-27T12:05:00.95788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became follower at term 1"} {"level":"info","ts":"2026-04-27T12:05:00.957926Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=(15902403223755824493)"} {"level":"warn","ts":"2026-04-27T12:05:00.971724Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-27T12:05:00.976274Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-27T12:05:00.977669Z","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-04-27T12:05:00.981906Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"dcb0af7a3afe1d6d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-27T12:05:00.983936Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"dcb0af7a3afe1d6d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-27T12:05:00.983762Z","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-04-27T12:05:00.984094Z","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-04-27T12:05:00.984158Z","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-04-27T12:05:00.986328Z","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-04-27T12:05:00.986506Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"dcb0af7a3afe1d6d","initial-advertise-peer-urls":["https://199.19.213.5:2380"],"listen-peer-urls":["https://199.19.213.5:2380"],"advertise-client-urls":["https://199.19.213.5:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.5:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-27T12:05:00.986531Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-27T12:05:00.986606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d switched to configuration voters=(15902403223755824493)"} {"level":"info","ts":"2026-04-27T12:05:00.986678Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4564295d4e5c9a3e","local-member-id":"dcb0af7a3afe1d6d","added-peer-id":"dcb0af7a3afe1d6d","added-peer-peer-urls":["https://199.19.213.5:2380"]} {"level":"info","ts":"2026-04-27T12:05:00.986763Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.5:2380"} {"level":"info","ts":"2026-04-27T12:05:00.986775Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.5:2380"} {"level":"info","ts":"2026-04-27T12:05:01.158711Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d is starting a new election at term 1"} {"level":"info","ts":"2026-04-27T12:05:01.158785Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became pre-candidate at term 1"} {"level":"info","ts":"2026-04-27T12:05:01.15885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d received MsgPreVoteResp from dcb0af7a3afe1d6d at term 1"} {"level":"info","ts":"2026-04-27T12:05:01.158877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became candidate at term 2"} {"level":"info","ts":"2026-04-27T12:05:01.158899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d received MsgVoteResp from dcb0af7a3afe1d6d at term 2"} {"level":"info","ts":"2026-04-27T12:05:01.158921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"dcb0af7a3afe1d6d became leader at term 2"} {"level":"info","ts":"2026-04-27T12:05:01.158945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: dcb0af7a3afe1d6d elected leader dcb0af7a3afe1d6d at term 2"} {"level":"info","ts":"2026-04-27T12:05:01.159925Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"dcb0af7a3afe1d6d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.5:2379]}","request-path":"/0/members/dcb0af7a3afe1d6d/attributes","cluster-id":"4564295d4e5c9a3e","publish-timeout":"7s"} {"level":"info","ts":"2026-04-27T12:05:01.15992Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:05:01.160152Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:05:01.160181Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-27T12:05:01.160635Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4564295d4e5c9a3e","local-member-id":"dcb0af7a3afe1d6d","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:05:01.160536Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-27T12:05:01.160709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-27T12:05:01.160872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:05:01.161017Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-27T12:05:01.165106Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-27T12:05:01.170866Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.5:2379"} {"level":"info","ts":"2026-04-27T12:05:16.004922Z","caller":"traceutil/trace.go:171","msg":"trace[542527710] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"133.967832ms","start":"2026-04-27T12:05:15.870922Z","end":"2026-04-27T12:05:16.00489Z","steps":["trace[542527710] 'process raft request' (duration: 133.860249ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:16.530843Z","caller":"traceutil/trace.go:171","msg":"trace[27840746] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"404.488268ms","start":"2026-04-27T12:05:16.126335Z","end":"2026-04-27T12:05:16.530823Z","steps":["trace[27840746] 'process raft request' (duration: 404.341984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:16.531234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:16.12632Z","time spent":"404.58251ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:17.133801Z","caller":"traceutil/trace.go:171","msg":"trace[1001268200] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"130.697607ms","start":"2026-04-27T12:05:17.003084Z","end":"2026-04-27T12:05:17.133781Z","steps":["trace[1001268200] 'process raft request' (duration: 130.396709ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:17.412196Z","caller":"traceutil/trace.go:171","msg":"trace[1456304024] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"269.980521ms","start":"2026-04-27T12:05:17.142181Z","end":"2026-04-27T12:05:17.412162Z","steps":["trace[1456304024] 'process raft request' (duration: 269.595811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:34.054487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.045352ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:05:34.054725Z","caller":"traceutil/trace.go:171","msg":"trace[924969519] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"548.76825ms","start":"2026-04-27T12:05:33.505939Z","end":"2026-04-27T12:05:34.054708Z","steps":["trace[924969519] 'read index received' (duration: 369.251266ms)","trace[924969519] 'applied index is now lower than readState.Index' (duration: 179.515794ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:34.05484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.904263ms","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-04-27T12:05:34.054881Z","caller":"traceutil/trace.go:171","msg":"trace[1141861024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:440; }","duration":"548.985165ms","start":"2026-04-27T12:05:33.505879Z","end":"2026-04-27T12:05:34.054864Z","steps":["trace[1141861024] 'agreement among raft nodes before linearized reading' (duration: 548.876953ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:34.054862Z","caller":"traceutil/trace.go:171","msg":"trace[2010078505] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"791.877037ms","start":"2026-04-27T12:05:33.26296Z","end":"2026-04-27T12:05:34.054837Z","steps":["trace[2010078505] 'process raft request' (duration: 791.659761ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:34.054847Z","caller":"traceutil/trace.go:171","msg":"trace[1750187668] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"820.28254ms","start":"2026-04-27T12:05:33.234518Z","end":"2026-04-27T12:05:34.054801Z","steps":["trace[1750187668] 'process raft request' (duration: 640.55153ms)","trace[1750187668] 'compare' (duration: 178.868248ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:34.054939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:33.505832Z","time spent":"549.094258ms","remote":"127.0.0.1:52834","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-04-27T12:05:34.05501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:33.262936Z","time spent":"792.000599ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:05:34.055057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:33.234502Z","time spent":"820.456114ms","remote":"127.0.0.1:52638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:05:36.557773Z","caller":"traceutil/trace.go:171","msg":"trace[1439188618] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"249.940065ms","start":"2026-04-27T12:05:36.307794Z","end":"2026-04-27T12:05:36.557734Z","steps":["trace[1439188618] 'process raft request' (duration: 248.665852ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:36.563367Z","caller":"traceutil/trace.go:171","msg":"trace[1652892281] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"147.975729ms","start":"2026-04-27T12:05:36.415362Z","end":"2026-04-27T12:05:36.563337Z","steps":["trace[1652892281] 'process raft request' (duration: 147.948018ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:36.563414Z","caller":"traceutil/trace.go:171","msg":"trace[959049137] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"246.199257ms","start":"2026-04-27T12:05:36.317188Z","end":"2026-04-27T12:05:36.563387Z","steps":["trace[959049137] 'process raft request' (duration: 245.862679ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:36.563446Z","caller":"traceutil/trace.go:171","msg":"trace[1852158285] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"217.807025ms","start":"2026-04-27T12:05:36.345602Z","end":"2026-04-27T12:05:36.563409Z","steps":["trace[1852158285] 'process raft request' (duration: 217.61909ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:37.484239Z","caller":"traceutil/trace.go:171","msg":"trace[1788467691] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"813.189263ms","start":"2026-04-27T12:05:36.67001Z","end":"2026-04-27T12:05:37.4832Z","steps":["trace[1788467691] 'process raft request' (duration: 812.792583ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.484512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:36.669981Z","time spent":"814.411126ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74745,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:37.492229Z","caller":"traceutil/trace.go:171","msg":"trace[1230093752] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"820.991019ms","start":"2026-04-27T12:05:36.671209Z","end":"2026-04-27T12:05:37.4922Z","steps":["trace[1230093752] 'process raft request' (duration: 820.655761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.492391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:36.671188Z","time spent":"821.131572ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:37.492483Z","caller":"traceutil/trace.go:171","msg":"trace[822526494] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"675.041032ms","start":"2026-04-27T12:05:36.817433Z","end":"2026-04-27T12:05:37.492474Z","steps":["trace[822526494] 'process raft request' (duration: 674.677003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.49252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:36.817404Z","time spent":"675.099782ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:05:37.492637Z","caller":"traceutil/trace.go:171","msg":"trace[1840080073] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"623.408931ms","start":"2026-04-27T12:05:36.869222Z","end":"2026-04-27T12:05:37.492631Z","steps":["trace[1840080073] 'process raft request' (duration: 623.036291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.49267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:36.869159Z","time spent":"623.497264ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-27T12:05:37.49279Z","caller":"traceutil/trace.go:171","msg":"trace[358054509] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"275.377971ms","start":"2026-04-27T12:05:37.217403Z","end":"2026-04-27T12:05:37.492781Z","steps":["trace[358054509] 'process raft request' (duration: 274.988171ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:37.492883Z","caller":"traceutil/trace.go:171","msg":"trace[2099011268] linearizableReadLoop","detail":"{readStateIndex:577; appliedIndex:573; }","duration":"479.262203ms","start":"2026-04-27T12:05:37.013589Z","end":"2026-04-27T12:05:37.492852Z","steps":["trace[2099011268] 'read index received' (duration: 469.260872ms)","trace[2099011268] 'applied index is now lower than readState.Index' (duration: 10.000291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:37.493007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.424357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:05:37.493029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.523834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mcq7l\" ","response":"range_response_count:1 size:4880"} {"level":"info","ts":"2026-04-27T12:05:37.493061Z","caller":"traceutil/trace.go:171","msg":"trace[297820777] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-mcq7l; range_end:; response_count:1; response_revision:561; }","duration":"436.583676ms","start":"2026-04-27T12:05:37.056471Z","end":"2026-04-27T12:05:37.493054Z","steps":["trace[297820777] 'agreement among raft nodes before linearized reading' (duration: 436.477393ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:37.49304Z","caller":"traceutil/trace.go:171","msg":"trace[1331080294] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:0; response_revision:561; }","duration":"479.484288ms","start":"2026-04-27T12:05:37.013543Z","end":"2026-04-27T12:05:37.493027Z","steps":["trace[1331080294] 'agreement among raft nodes before linearized reading' (duration: 479.396176ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.493082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:37.056454Z","time spent":"436.621997ms","remote":"127.0.0.1:52740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4903,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-mcq7l\" "} {"level":"warn","ts":"2026-04-27T12:05:37.493094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:37.013519Z","time spent":"479.56896ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-04-27T12:05:37.49317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.187889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:05:37.493192Z","caller":"traceutil/trace.go:171","msg":"trace[1645450026] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:561; }","duration":"313.276211ms","start":"2026-04-27T12:05:37.17991Z","end":"2026-04-27T12:05:37.493186Z","steps":["trace[1645450026] 'agreement among raft nodes before linearized reading' (duration: 313.24209ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:37.49321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:37.179892Z","time spent":"313.313892ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:05:37.493235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.055416ms","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-04-27T12:05:37.49329Z","caller":"traceutil/trace.go:171","msg":"trace[1066566074] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:561; }","duration":"248.136409ms","start":"2026-04-27T12:05:37.24514Z","end":"2026-04-27T12:05:37.493277Z","steps":["trace[1066566074] 'agreement among raft nodes before linearized reading' (duration: 248.055476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.360844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.034703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:29476"} {"level":"info","ts":"2026-04-27T12:05:38.360909Z","caller":"traceutil/trace.go:171","msg":"trace[1721434332] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:583; }","duration":"345.127745ms","start":"2026-04-27T12:05:38.015767Z","end":"2026-04-27T12:05:38.360894Z","steps":["trace[1721434332] 'range keys from in-memory index tree' (duration: 344.941581ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.360937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.015754Z","time spent":"345.176976ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":1,"response size":29499,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xlistenersets.gateway.networking.x-k8s.io\" "} {"level":"warn","ts":"2026-04-27T12:05:38.360868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.358205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-27T12:05:38.361016Z","caller":"traceutil/trace.go:171","msg":"trace[886275864] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:583; }","duration":"344.522119ms","start":"2026-04-27T12:05:38.016463Z","end":"2026-04-27T12:05:38.360985Z","steps":["trace[886275864] 'range keys from in-memory index tree' (duration: 344.227401ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.361099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.016435Z","time spent":"344.637092ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-04-27T12:05:38.586807Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208907476,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-27T12:05:38.588539Z","caller":"traceutil/trace.go:171","msg":"trace[261661391] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"558.125994ms","start":"2026-04-27T12:05:38.030382Z","end":"2026-04-27T12:05:38.588508Z","steps":["trace[261661391] 'process raft request' (duration: 557.066596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.588696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.030358Z","time spent":"558.253237ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:38.591849Z","caller":"traceutil/trace.go:171","msg":"trace[399029334] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:600; }","duration":"505.391295ms","start":"2026-04-27T12:05:38.086437Z","end":"2026-04-27T12:05:38.591828Z","steps":["trace[399029334] 'read index received' (duration: 500.828595ms)","trace[399029334] 'applied index is now lower than readState.Index' (duration: 4.56143ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:38.591911Z","caller":"traceutil/trace.go:171","msg":"trace[1944470919] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"223.023132ms","start":"2026-04-27T12:05:38.368859Z","end":"2026-04-27T12:05:38.591882Z","steps":["trace[1944470919] 'process raft request' (duration: 222.820126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.59214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.678343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T12:05:38.592207Z","caller":"traceutil/trace.go:171","msg":"trace[1938497496] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:586; }","duration":"505.791095ms","start":"2026-04-27T12:05:38.086399Z","end":"2026-04-27T12:05:38.59219Z","steps":["trace[1938497496] 'agreement among raft nodes before linearized reading' (duration: 505.549609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.086368Z","time spent":"505.873057ms","remote":"127.0.0.1:52834","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-04-27T12:05:38.592279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.274068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T12:05:38.592344Z","caller":"traceutil/trace.go:171","msg":"trace[1625811546] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:586; }","duration":"353.376229ms","start":"2026-04-27T12:05:38.238956Z","end":"2026-04-27T12:05:38.592332Z","steps":["trace[1625811546] 'agreement among raft nodes before linearized reading' (duration: 353.190865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.878259ms","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-04-27T12:05:38.592372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.238942Z","time spent":"353.42267ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-27T12:05:38.592405Z","caller":"traceutil/trace.go:171","msg":"trace[2057207471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:586; }","duration":"490.044203ms","start":"2026-04-27T12:05:38.102335Z","end":"2026-04-27T12:05:38.592379Z","steps":["trace[2057207471] 'agreement among raft nodes before linearized reading' (duration: 489.695774ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.102321Z","time spent":"490.124677ms","remote":"127.0.0.1:52834","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-04-27T12:05:38.592496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.395333ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:05:38.592522Z","caller":"traceutil/trace.go:171","msg":"trace[1575369568] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"412.447195ms","start":"2026-04-27T12:05:38.180067Z","end":"2026-04-27T12:05:38.592514Z","steps":["trace[1575369568] 'agreement among raft nodes before linearized reading' (duration: 412.403104ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.180053Z","time spent":"412.487186ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:05:38.592557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.655685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:9149"} {"level":"info","ts":"2026-04-27T12:05:38.592587Z","caller":"traceutil/trace.go:171","msg":"trace[1701264205] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xmeshes.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:586; }","duration":"224.703256ms","start":"2026-04-27T12:05:38.367875Z","end":"2026-04-27T12:05:38.592578Z","steps":["trace[1701264205] 'agreement among raft nodes before linearized reading' (duration: 224.338206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.99944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-27T12:05:38.592701Z","caller":"traceutil/trace.go:171","msg":"trace[151042751] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"379.078382ms","start":"2026-04-27T12:05:38.213616Z","end":"2026-04-27T12:05:38.592694Z","steps":["trace[151042751] 'agreement among raft nodes before linearized reading' (duration: 378.817896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:38.592718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:38.213595Z","time spent":"379.118244ms","remote":"127.0.0.1:52582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4356,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-27T12:05:38.593277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.797095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-04-27T12:05:38.593366Z","caller":"traceutil/trace.go:171","msg":"trace[1434070983] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:586; }","duration":"179.528595ms","start":"2026-04-27T12:05:38.413818Z","end":"2026-04-27T12:05:38.593347Z","steps":["trace[1434070983] 'agreement among raft nodes before linearized reading' (duration: 178.261861ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:44.679185Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208907686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:05:45.179716Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208907686,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:05:45.490783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.199691ms","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-04-27T12:05:45.490912Z","caller":"traceutil/trace.go:171","msg":"trace[1238928724] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:636; }","duration":"227.339864ms","start":"2026-04-27T12:05:45.263551Z","end":"2026-04-27T12:05:45.490891Z","steps":["trace[1238928724] 'range keys from in-memory index tree' (duration: 227.182721ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:45.490961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415949424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-pnqkb\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-04-27T12:05:45.491024Z","caller":"traceutil/trace.go:171","msg":"trace[50293122] range","detail":"{range_begin:/registry/pods/kube-system/cilium-pnqkb; range_end:; response_count:1; response_revision:636; }","duration":"1.416053567s","start":"2026-04-27T12:05:44.074954Z","end":"2026-04-27T12:05:45.491008Z","steps":["trace[50293122] 'range keys from in-memory index tree' (duration: 1.415847652s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:45.491053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.074936Z","time spent":"1.416111548s","remote":"127.0.0.1:52740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-pnqkb\" "} {"level":"warn","ts":"2026-04-27T12:05:45.563178Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.423384674s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T12:05:45.564256Z","caller":"traceutil/trace.go:171","msg":"trace[944601937] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.426082543s","start":"2026-04-27T12:05:44.138148Z","end":"2026-04-27T12:05:45.564231Z","steps":["trace[944601937] 'process raft request' (duration: 1.425494367s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:45.56447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.138117Z","time spent":"1.426272197s","remote":"127.0.0.1:52636","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:05:46.179167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000189489s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-27T12:05:46.179242Z","caller":"traceutil/trace.go:171","msg":"trace[2053442651] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000315092s","start":"2026-04-27T12:05:44.178911Z","end":"2026-04-27T12:05:46.179226Z","steps":["trace[2053442651] 'agreement among raft nodes before linearized reading' (duration: 2.000217999s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:46.179283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.178888Z","time spent":"2.000386214s","remote":"127.0.0.1:52544","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-04-27T12:05:47.060263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"956.548067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:05:47.0605Z","caller":"traceutil/trace.go:171","msg":"trace[1139449261] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:654; }","duration":"2.881525481s","start":"2026-04-27T12:05:44.178958Z","end":"2026-04-27T12:05:47.060483Z","steps":["trace[1139449261] 'read index received' (duration: 1.384450044s)","trace[1139449261] 'applied index is now lower than readState.Index' (duration: 1.497074197s)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:47.060569Z","caller":"traceutil/trace.go:171","msg":"trace[444690526] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"2.835926891s","start":"2026-04-27T12:05:44.224632Z","end":"2026-04-27T12:05:47.060559Z","steps":["trace[444690526] 'process raft request' (duration: 1.878962485s)","trace[444690526] 'compare' (duration: 956.299579ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:47.060624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.224609Z","time spent":"2.835983673s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:47.060995Z","caller":"traceutil/trace.go:171","msg":"trace[1302874826] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.556826218s","start":"2026-04-27T12:05:45.504158Z","end":"2026-04-27T12:05:47.060984Z","steps":["trace[1302874826] 'process raft request' (duration: 1.556260104s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.061052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:45.504128Z","time spent":"1.5568946s","remote":"127.0.0.1:52740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:47.093197Z","caller":"traceutil/trace.go:171","msg":"trace[1900500199] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"1.47001551s","start":"2026-04-27T12:05:45.623144Z","end":"2026-04-27T12:05:47.093159Z","steps":["trace[1900500199] 'process raft request' (duration: 1.469813295s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.093525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:45.623085Z","time spent":"1.470211294s","remote":"127.0.0.1:52812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T12:05:47.118961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.405530511s","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-04-27T12:05:47.118989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.453767238s","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-04-27T12:05:47.119026Z","caller":"traceutil/trace.go:171","msg":"trace[1613994066] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:641; }","duration":"2.45384117s","start":"2026-04-27T12:05:44.665174Z","end":"2026-04-27T12:05:47.119015Z","steps":["trace[1613994066] 'agreement among raft nodes before linearized reading' (duration: 2.453726937s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.119056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.665145Z","time spent":"2.453904142s","remote":"127.0.0.1:52834","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-04-27T12:05:47.119058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.001471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:05:47.119078Z","caller":"traceutil/trace.go:171","msg":"trace[1121397226] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"932.044512ms","start":"2026-04-27T12:05:46.187028Z","end":"2026-04-27T12:05:47.119072Z","steps":["trace[1121397226] 'agreement among raft nodes before linearized reading' (duration: 932.014282ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.119097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:46.187014Z","time spent":"932.079033ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:05:47.119026Z","caller":"traceutil/trace.go:171","msg":"trace[1510309217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:641; }","duration":"2.405613513s","start":"2026-04-27T12:05:44.713397Z","end":"2026-04-27T12:05:47.119011Z","steps":["trace[1510309217] 'agreement among raft nodes before linearized reading' (duration: 2.405444169s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.119219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.713352Z","time spent":"2.405863109s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T12:05:47.119281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.484939061s","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-04-27T12:05:47.119413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.485199907s","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-04-27T12:05:47.119438Z","caller":"traceutil/trace.go:171","msg":"trace[573182648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:641; }","duration":"2.4852574s","start":"2026-04-27T12:05:44.634174Z","end":"2026-04-27T12:05:47.119432Z","steps":["trace[573182648] 'agreement among raft nodes before linearized reading' (duration: 2.485199959s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.119453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.634157Z","time spent":"2.4852931s","remote":"127.0.0.1:52834","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-04-27T12:05:47.119469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.592420714s","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-04-27T12:05:47.119518Z","caller":"traceutil/trace.go:171","msg":"trace[1515725157] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:641; }","duration":"1.592498455s","start":"2026-04-27T12:05:45.527013Z","end":"2026-04-27T12:05:47.119511Z","steps":["trace[1515725157] 'agreement among raft nodes before linearized reading' (duration: 1.592403763s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.11954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:45.526988Z","time spent":"1.592545386s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:05:47.119776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.710325017s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.5\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-27T12:05:47.1198Z","caller":"traceutil/trace.go:171","msg":"trace[2025376363] range","detail":"{range_begin:/registry/masterleases/199.19.213.5; range_end:; response_count:1; response_revision:641; }","duration":"2.710383078s","start":"2026-04-27T12:05:44.409411Z","end":"2026-04-27T12:05:47.119794Z","steps":["trace[2025376363] 'agreement among raft nodes before linearized reading' (duration: 2.710334726s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.119834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.40939Z","time spent":"2.710438209s","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.19.213.5\" "} {"level":"info","ts":"2026-04-27T12:05:47.119812Z","caller":"traceutil/trace.go:171","msg":"trace[790515075] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"936.665571ms","start":"2026-04-27T12:05:46.183122Z","end":"2026-04-27T12:05:47.119787Z","steps":["trace[790515075] 'process raft request' (duration: 935.543961ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:47.119406Z","caller":"traceutil/trace.go:171","msg":"trace[87244477] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:641; }","duration":"2.485156246s","start":"2026-04-27T12:05:44.634226Z","end":"2026-04-27T12:05:47.119382Z","steps":["trace[87244477] 'agreement among raft nodes before linearized reading' (duration: 2.484647954s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:47.120198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:44.634208Z","time spent":"2.485981107s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:05:47.120564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:46.183097Z","time spent":"936.954027ms","remote":"127.0.0.1:52616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T12:05:50.075634Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.342019373s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T12:05:50.096917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.933252578s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:05:50.097365Z","caller":"traceutil/trace.go:171","msg":"trace[306725842] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.36390307s","start":"2026-04-27T12:05:48.733445Z","end":"2026-04-27T12:05:50.097348Z","steps":["trace[306725842] 'process raft request' (duration: 1.363862769s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.097442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:48.733413Z","time spent":"1.363995322s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:50.097493Z","caller":"traceutil/trace.go:171","msg":"trace[376299271] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"2.973755519s","start":"2026-04-27T12:05:47.123714Z","end":"2026-04-27T12:05:50.09747Z","steps":["trace[376299271] 'process raft request' (duration: 2.97341093s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.097627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.123705Z","time spent":"2.973844882s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:50.097664Z","caller":"traceutil/trace.go:171","msg":"trace[1441589271] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"2.973120762s","start":"2026-04-27T12:05:47.124536Z","end":"2026-04-27T12:05:50.097657Z","steps":["trace[1441589271] 'process raft request' (duration: 2.972699462s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.097717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.124527Z","time spent":"2.973161073s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:50.097773Z","caller":"traceutil/trace.go:171","msg":"trace[984773845] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"2.974421906s","start":"2026-04-27T12:05:47.123341Z","end":"2026-04-27T12:05:50.097763Z","steps":["trace[984773845] 'process raft request' (duration: 40.234753ms)","trace[984773845] 'compare' (duration: 2.932968761s)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:50.097822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.12332Z","time spent":"2.974477407s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:50.097952Z","caller":"traceutil/trace.go:171","msg":"trace[1203413051] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"2.974347234s","start":"2026-04-27T12:05:47.123599Z","end":"2026-04-27T12:05:50.097946Z","steps":["trace[1203413051] 'process raft request' (duration: 2.973395809s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.097994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.123587Z","time spent":"2.974388145s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:05:50.098009Z","caller":"traceutil/trace.go:171","msg":"trace[727458967] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:659; }","duration":"2.974365775s","start":"2026-04-27T12:05:47.123624Z","end":"2026-04-27T12:05:50.09799Z","steps":["trace[727458967] 'read index received' (duration: 3.378007ms)","trace[727458967] 'applied index is now lower than readState.Index' (duration: 2.970985718s)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:50.097632Z","caller":"traceutil/trace.go:171","msg":"trace[1734431488] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"2.973914983s","start":"2026-04-27T12:05:47.123707Z","end":"2026-04-27T12:05:50.097622Z","steps":["trace[1734431488] 'process raft request' (duration: 2.973494753s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.098093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.12368Z","time spent":"2.974396325s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:05:50.098183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.974536999s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-27T12:05:50.098203Z","caller":"traceutil/trace.go:171","msg":"trace[1533745718] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"2.969999102s","start":"2026-04-27T12:05:47.128171Z","end":"2026-04-27T12:05:50.098171Z","steps":["trace[1533745718] 'process raft request' (duration: 2.96910318s)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:05:50.098242Z","caller":"traceutil/trace.go:171","msg":"trace[25413860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:648; }","duration":"2.974630701s","start":"2026-04-27T12:05:47.123596Z","end":"2026-04-27T12:05:50.098227Z","steps":["trace[25413860] 'agreement among raft nodes before linearized reading' (duration: 2.974514208s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.098353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.123579Z","time spent":"2.974759534s","remote":"127.0.0.1:52758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-04-27T12:05:50.09836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:47.128161Z","time spent":"2.970118645s","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:05:50.107312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.927855298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:05:50.107388Z","caller":"traceutil/trace.go:171","msg":"trace[483230649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:648; }","duration":"1.927958981s","start":"2026-04-27T12:05:48.179412Z","end":"2026-04-27T12:05:50.107371Z","steps":["trace[483230649] 'agreement among raft nodes before linearized reading' (duration: 1.927846278s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:05:50.107424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:05:48.179396Z","time spent":"1.928019952s","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:05:53.326139Z","caller":"traceutil/trace.go:171","msg":"trace[1183078270] linearizableReadLoop","detail":"{readStateIndex:688; appliedIndex:687; }","duration":"145.699377ms","start":"2026-04-27T12:05:53.180414Z","end":"2026-04-27T12:05:53.326113Z","steps":["trace[1183078270] 'read index received' (duration: 96.539317ms)","trace[1183078270] 'applied index is now lower than readState.Index' (duration: 49.15918ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:05:53.326186Z","caller":"traceutil/trace.go:171","msg":"trace[1625792191] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"198.003568ms","start":"2026-04-27T12:05:53.128135Z","end":"2026-04-27T12:05:53.326139Z","steps":["trace[1625792191] 'process raft request' (duration: 148.886998ms)","trace[1625792191] 'compare' (duration: 48.964316ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:05:53.326263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.83596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:05:53.326311Z","caller":"traceutil/trace.go:171","msg":"trace[1921112276] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:668; }","duration":"145.937762ms","start":"2026-04-27T12:05:53.180349Z","end":"2026-04-27T12:05:53.326286Z","steps":["trace[1921112276] 'agreement among raft nodes before linearized reading' (duration: 145.86027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:06.745275Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208907934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:06:07.266376Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208907934,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:06:07.659366Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.413296067s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T12:06:07.659985Z","caller":"traceutil/trace.go:171","msg":"trace[2014834336] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"1.41501335s","start":"2026-04-27T12:06:06.244947Z","end":"2026-04-27T12:06:07.659961Z","steps":["trace[2014834336] 'read index received' (duration: 1.414697373s)","trace[2014834336] 'applied index is now lower than readState.Index' (duration: 314.677µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:07.660099Z","caller":"traceutil/trace.go:171","msg":"trace[935616863] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"1.456531806s","start":"2026-04-27T12:06:06.203556Z","end":"2026-04-27T12:06:07.660088Z","steps":["trace[935616863] 'process raft request' (duration: 1.456227769s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:07.66021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:06.203547Z","time spent":"1.456580827s","remote":"127.0.0.1:52740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4023,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:07.660509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.415534533s","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-04-27T12:06:07.660601Z","caller":"traceutil/trace.go:171","msg":"trace[235252990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"1.415689877s","start":"2026-04-27T12:06:06.244888Z","end":"2026-04-27T12:06:07.660578Z","steps":["trace[235252990] 'agreement among raft nodes before linearized reading' (duration: 1.415375809s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:07.660648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:06.244868Z","time spent":"1.415768519s","remote":"127.0.0.1:52834","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-04-27T12:06:07.66275Z","caller":"traceutil/trace.go:171","msg":"trace[380076193] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"394.337154ms","start":"2026-04-27T12:06:07.268383Z","end":"2026-04-27T12:06:07.66272Z","steps":["trace[380076193] 'process raft request' (duration: 393.561864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:07.662886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:07.268364Z","time spent":"394.454796ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:07.662906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.86205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:07.66299Z","caller":"traceutil/trace.go:171","msg":"trace[1799282655] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:765; }","duration":"481.978753ms","start":"2026-04-27T12:06:07.180987Z","end":"2026-04-27T12:06:07.662966Z","steps":["trace[1799282655] 'agreement among raft nodes before linearized reading' (duration: 481.8497ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:07.663044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:07.180968Z","time spent":"482.063874ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:06:07.663184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416415376s","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-04-27T12:06:07.663285Z","caller":"traceutil/trace.go:171","msg":"trace[418540995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:765; }","duration":"1.417481934s","start":"2026-04-27T12:06:06.245778Z","end":"2026-04-27T12:06:07.66326Z","steps":["trace[418540995] 'agreement among raft nodes before linearized reading' (duration: 1.416280813s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:07.663341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:06.245766Z","time spent":"1.417556775s","remote":"127.0.0.1:52834","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-04-27T12:06:43.339795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"803.109232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:43.339876Z","caller":"traceutil/trace.go:171","msg":"trace[970812405] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:980; }","duration":"803.243095ms","start":"2026-04-27T12:06:42.536618Z","end":"2026-04-27T12:06:43.339861Z","steps":["trace[970812405] 'count revisions from in-memory index tree' (duration: 803.00791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:43.339904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:42.536595Z","time spent":"803.302498ms","remote":"127.0.0.1:52674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:43.339787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"878.290035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:06:43.339981Z","caller":"traceutil/trace.go:171","msg":"trace[2127412790] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:980; }","duration":"878.525362ms","start":"2026-04-27T12:06:42.461439Z","end":"2026-04-27T12:06:43.339964Z","steps":["trace[2127412790] 'count revisions from in-memory index tree' (duration: 878.240533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:43.340012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:42.461425Z","time spent":"878.578823ms","remote":"127.0.0.1:36976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:44.111583Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208908392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:06:44.612426Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208908392,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:06:44.878187Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.129189669s","expected-duration":"1s"} {"level":"info","ts":"2026-04-27T12:06:44.87846Z","caller":"traceutil/trace.go:171","msg":"trace[1238790623] linearizableReadLoop","detail":"{readStateIndex:1014; appliedIndex:1013; }","duration":"1.267019763s","start":"2026-04-27T12:06:43.611422Z","end":"2026-04-27T12:06:44.878442Z","steps":["trace[1238790623] 'read index received' (duration: 1.26689726s)","trace[1238790623] 'applied index is now lower than readState.Index' (duration: 121.573µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:06:44.878556Z","caller":"traceutil/trace.go:171","msg":"trace[1272648675] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"2.12963499s","start":"2026-04-27T12:06:42.74891Z","end":"2026-04-27T12:06:44.878545Z","steps":["trace[1272648675] 'process raft request' (duration: 2.129434355s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.878643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:42.74889Z","time spent":"2.129688321s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:06:44.878726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028420343s","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-04-27T12:06:44.878767Z","caller":"traceutil/trace.go:171","msg":"trace[1781137388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.028511177s","start":"2026-04-27T12:06:43.850239Z","end":"2026-04-27T12:06:44.87875Z","steps":["trace[1781137388] 'agreement among raft nodes before linearized reading' (duration: 1.028342392s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.87879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"501.084908ms","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-04-27T12:06:44.878799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:43.850217Z","time spent":"1.028575128s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:06:44.878771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.088348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.5\" ","response":"range_response_count:1 size:131"} {"level":"warn","ts":"2026-04-27T12:06:44.878848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.683667ms","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-04-27T12:06:44.878861Z","caller":"traceutil/trace.go:171","msg":"trace[303201538] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:981; }","duration":"500.740089ms","start":"2026-04-27T12:06:44.378117Z","end":"2026-04-27T12:06:44.878858Z","steps":["trace[303201538] 'agreement among raft nodes before linearized reading' (duration: 500.704698ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.878876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:44.378097Z","time spent":"500.77573ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-27T12:06:44.878851Z","caller":"traceutil/trace.go:171","msg":"trace[98659528] range","detail":"{range_begin:/registry/masterleases/199.19.213.5; range_end:; response_count:1; response_revision:981; }","duration":"466.207462ms","start":"2026-04-27T12:06:44.412623Z","end":"2026-04-27T12:06:44.87883Z","steps":["trace[98659528] 'agreement among raft nodes before linearized reading' (duration: 465.979785ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.878937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:44.412602Z","time spent":"466.322934ms","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.19.213.5\" "} {"level":"warn","ts":"2026-04-27T12:06:44.878945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028627798s","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-04-27T12:06:44.878965Z","caller":"traceutil/trace.go:171","msg":"trace[1546475874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"1.028661049s","start":"2026-04-27T12:06:43.850298Z","end":"2026-04-27T12:06:44.878959Z","steps":["trace[1546475874] 'agreement among raft nodes before linearized reading' (duration: 1.028610797s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.878982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:43.850292Z","time spent":"1.02868621s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T12:06:44.87881Z","caller":"traceutil/trace.go:171","msg":"trace[1327431833] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:981; }","duration":"501.139459ms","start":"2026-04-27T12:06:44.377665Z","end":"2026-04-27T12:06:44.878804Z","steps":["trace[1327431833] 'agreement among raft nodes before linearized reading' (duration: 501.065207ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.879074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:44.377646Z","time spent":"501.423835ms","remote":"127.0.0.1:52834","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-04-27T12:06:44.879105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.26768049s","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":"info","ts":"2026-04-27T12:06:44.879123Z","caller":"traceutil/trace.go:171","msg":"trace[2145552993] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:981; }","duration":"1.267699851s","start":"2026-04-27T12:06:43.611418Z","end":"2026-04-27T12:06:44.879118Z","steps":["trace[2145552993] 'agreement among raft nodes before linearized reading' (duration: 1.2676704s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.879137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:43.611402Z","time spent":"1.267731342s","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-04-27T12:06:44.878897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.689275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:06:44.879171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.13451ms","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-04-27T12:06:44.879181Z","caller":"traceutil/trace.go:171","msg":"trace[1981624391] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"699.002922ms","start":"2026-04-27T12:06:44.180166Z","end":"2026-04-27T12:06:44.879169Z","steps":["trace[1981624391] 'agreement among raft nodes before linearized reading' (duration: 698.714375ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:06:44.879199Z","caller":"traceutil/trace.go:171","msg":"trace[224522329] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"466.19185ms","start":"2026-04-27T12:06:44.412999Z","end":"2026-04-27T12:06:44.87919Z","steps":["trace[224522329] 'agreement among raft nodes before linearized reading' (duration: 466.112218ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:06:44.879206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:44.180146Z","time spent":"699.053183ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:06:44.879225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:06:44.412986Z","time spent":"466.231792ms","remote":"127.0.0.1:52834","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-04-27T12:07:07.170006Z","caller":"traceutil/trace.go:171","msg":"trace[531429715] linearizableReadLoop","detail":"{readStateIndex:1092; appliedIndex:1091; }","duration":"162.85625ms","start":"2026-04-27T12:07:07.007084Z","end":"2026-04-27T12:07:07.16994Z","steps":["trace[531429715] 'read index received' (duration: 162.767268ms)","trace[531429715] 'applied index is now lower than readState.Index' (duration: 87.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:07.170068Z","caller":"traceutil/trace.go:171","msg":"trace[1720534610] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"212.57775ms","start":"2026-04-27T12:07:06.957462Z","end":"2026-04-27T12:07:07.17004Z","steps":["trace[1720534610] 'process raft request' (duration: 212.325324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:07.170256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.152038ms","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-04-27T12:07:07.17031Z","caller":"traceutil/trace.go:171","msg":"trace[804567987] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1054; }","duration":"163.24711ms","start":"2026-04-27T12:07:07.007049Z","end":"2026-04-27T12:07:07.170297Z","steps":["trace[804567987] 'agreement among raft nodes before linearized reading' (duration: 163.091066ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:07.170546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.745297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T12:07:07.170611Z","caller":"traceutil/trace.go:171","msg":"trace[1641224340] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1054; }","duration":"140.831399ms","start":"2026-04-27T12:07:07.029768Z","end":"2026-04-27T12:07:07.170599Z","steps":["trace[1641224340] 'agreement among raft nodes before linearized reading' (duration: 140.722527ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:07.170672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.115687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-04-27T12:07:07.170736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.948043ms","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-04-27T12:07:07.170777Z","caller":"traceutil/trace.go:171","msg":"trace[1556944677] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1054; }","duration":"141.122368ms","start":"2026-04-27T12:07:07.029646Z","end":"2026-04-27T12:07:07.170768Z","steps":["trace[1556944677] 'agreement among raft nodes before linearized reading' (duration: 140.925533ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:07.170852Z","caller":"traceutil/trace.go:171","msg":"trace[626360773] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1054; }","duration":"146.190039ms","start":"2026-04-27T12:07:07.024504Z","end":"2026-04-27T12:07:07.170694Z","steps":["trace[626360773] 'agreement among raft nodes before linearized reading' (duration: 146.075886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:07.171367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.531843ms","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-04-27T12:07:07.171573Z","caller":"traceutil/trace.go:171","msg":"trace[1248450262] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1054; }","duration":"152.668187ms","start":"2026-04-27T12:07:07.01876Z","end":"2026-04-27T12:07:07.171428Z","steps":["trace[1248450262] 'agreement among raft nodes before linearized reading' (duration: 151.729483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:07.679437Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208908601,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:07:07.97553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.570071ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:07:07.975671Z","caller":"traceutil/trace.go:171","msg":"trace[143479972] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"800.788065ms","start":"2026-04-27T12:07:07.174854Z","end":"2026-04-27T12:07:07.975642Z","steps":["trace[143479972] 'process raft request' (duration: 505.011228ms)","trace[143479972] 'compare' (duration: 295.251423ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:07.975761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.174835Z","time spent":"800.887398ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:08.145996Z","caller":"traceutil/trace.go:171","msg":"trace[884604895] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"970.913605ms","start":"2026-04-27T12:07:07.175009Z","end":"2026-04-27T12:07:08.145922Z","steps":["trace[884604895] 'process raft request' (duration: 970.499933ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:08.146173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.175001Z","time spent":"971.089739ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:08.146887Z","caller":"traceutil/trace.go:171","msg":"trace[559982863] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"971.581532ms","start":"2026-04-27T12:07:07.175286Z","end":"2026-04-27T12:07:08.146867Z","steps":["trace[559982863] 'process raft request' (duration: 971.232953ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:08.146972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.175266Z","time spent":"971.661313ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:08.147306Z","caller":"traceutil/trace.go:171","msg":"trace[212835814] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"971.407937ms","start":"2026-04-27T12:07:07.175296Z","end":"2026-04-27T12:07:08.146704Z","steps":["trace[212835814] 'process raft request' (duration: 971.037338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:08.147391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.175289Z","time spent":"972.060635ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:07:08.148682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"970.226097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:08.147975Z","caller":"traceutil/trace.go:171","msg":"trace[1280158893] linearizableReadLoop","detail":"{readStateIndex:1097; appliedIndex:1092; }","duration":"969.480037ms","start":"2026-04-27T12:07:07.178425Z","end":"2026-04-27T12:07:08.147905Z","steps":["trace[1280158893] 'read index received' (duration: 501.359574ms)","trace[1280158893] 'applied index is now lower than readState.Index' (duration: 468.117603ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:08.148771Z","caller":"traceutil/trace.go:171","msg":"trace[1210851542] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1059; }","duration":"970.350669ms","start":"2026-04-27T12:07:07.178401Z","end":"2026-04-27T12:07:08.148751Z","steps":["trace[1210851542] 'agreement among raft nodes before linearized reading' (duration: 970.171745ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:08.148787Z","caller":"traceutil/trace.go:171","msg":"trace[596973498] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"971.239073ms","start":"2026-04-27T12:07:07.175193Z","end":"2026-04-27T12:07:08.146432Z","steps":["trace[596973498] 'process raft request' (duration: 970.957215ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:08.14883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.178392Z","time spent":"970.429572ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:07:08.148899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:07.175181Z","time spent":"973.645055ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:09.328326Z","caller":"traceutil/trace.go:171","msg":"trace[427612361] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"148.897229ms","start":"2026-04-27T12:07:09.179379Z","end":"2026-04-27T12:07:09.328276Z","steps":["trace[427612361] 'process raft request' (duration: 148.767276ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:09.328799Z","caller":"traceutil/trace.go:171","msg":"trace[456676239] linearizableReadLoop","detail":"{readStateIndex:1099; appliedIndex:1099; }","duration":"148.153139ms","start":"2026-04-27T12:07:09.180616Z","end":"2026-04-27T12:07:09.328769Z","steps":["trace[456676239] 'read index received' (duration: 148.135019ms)","trace[456676239] 'applied index is now lower than readState.Index' (duration: 15.24µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:09.328962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.311674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:09.328994Z","caller":"traceutil/trace.go:171","msg":"trace[850449149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"148.389585ms","start":"2026-04-27T12:07:09.180596Z","end":"2026-04-27T12:07:09.328985Z","steps":["trace[850449149] 'agreement among raft nodes before linearized reading' (duration: 148.274853ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:10.291203Z","caller":"traceutil/trace.go:171","msg":"trace[2089429653] linearizableReadLoop","detail":"{readStateIndex:1101; appliedIndex:1100; }","duration":"281.575327ms","start":"2026-04-27T12:07:10.009606Z","end":"2026-04-27T12:07:10.291181Z","steps":["trace[2089429653] 'read index received' (duration: 281.412353ms)","trace[2089429653] 'applied index is now lower than readState.Index' (duration: 162.044µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:10.291312Z","caller":"traceutil/trace.go:171","msg":"trace[929126835] transaction","detail":"{read_only:false; response_revision:1062; number_of_response:1; }","duration":"304.898211ms","start":"2026-04-27T12:07:09.986399Z","end":"2026-04-27T12:07:10.291297Z","steps":["trace[929126835] 'process raft request' (duration: 304.629385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:10.291393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:09.986386Z","time spent":"304.955683ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:07:10.29137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.752402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:07:10.291451Z","caller":"traceutil/trace.go:171","msg":"trace[184336343] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1062; }","duration":"281.836215ms","start":"2026-04-27T12:07:10.00959Z","end":"2026-04-27T12:07:10.291427Z","steps":["trace[184336343] 'agreement among raft nodes before linearized reading' (duration: 281.716981ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:10.291504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.180739ms","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-04-27T12:07:10.291567Z","caller":"traceutil/trace.go:171","msg":"trace[398983905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1062; }","duration":"141.281362ms","start":"2026-04-27T12:07:10.15027Z","end":"2026-04-27T12:07:10.291552Z","steps":["trace[398983905] 'agreement among raft nodes before linearized reading' (duration: 141.108926ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:10.291569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.026449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T12:07:10.29163Z","caller":"traceutil/trace.go:171","msg":"trace[973589710] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1062; }","duration":"140.132581ms","start":"2026-04-27T12:07:10.151483Z","end":"2026-04-27T12:07:10.291616Z","steps":["trace[973589710] 'agreement among raft nodes before linearized reading' (duration: 139.947227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:10.291626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.549873ms","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-04-27T12:07:10.291683Z","caller":"traceutil/trace.go:171","msg":"trace[1407344297] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1062; }","duration":"140.638614ms","start":"2026-04-27T12:07:10.151029Z","end":"2026-04-27T12:07:10.291668Z","steps":["trace[1407344297] 'agreement among raft nodes before linearized reading' (duration: 140.46923ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:10.29183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.725935ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T12:07:10.291888Z","caller":"traceutil/trace.go:171","msg":"trace[1335347024] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1062; }","duration":"138.797447ms","start":"2026-04-27T12:07:10.153077Z","end":"2026-04-27T12:07:10.291875Z","steps":["trace[1335347024] 'agreement among raft nodes before linearized reading' (duration: 138.643873ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:12.188808Z","caller":"traceutil/trace.go:171","msg":"trace[1524589188] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"339.486639ms","start":"2026-04-27T12:07:11.849294Z","end":"2026-04-27T12:07:12.188781Z","steps":["trace[1524589188] 'process raft request' (duration: 339.276853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:12.188966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:11.849261Z","time spent":"339.634023ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:07:12.516627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.381064ms","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-04-27T12:07:12.516809Z","caller":"traceutil/trace.go:171","msg":"trace[705985568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1068; }","duration":"173.642071ms","start":"2026-04-27T12:07:12.343148Z","end":"2026-04-27T12:07:12.51679Z","steps":["trace[705985568] 'range keys from in-memory index tree' (duration: 173.115227ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:12.516988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.984834ms","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-04-27T12:07:12.517069Z","caller":"traceutil/trace.go:171","msg":"trace[2087972236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1068; }","duration":"222.114097ms","start":"2026-04-27T12:07:12.294937Z","end":"2026-04-27T12:07:12.517051Z","steps":["trace[2087972236] 'range keys from in-memory index tree' (duration: 221.799429ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:12.517204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.225086ms","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-04-27T12:07:12.517272Z","caller":"traceutil/trace.go:171","msg":"trace[634741778] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1068; }","duration":"174.301858ms","start":"2026-04-27T12:07:12.342954Z","end":"2026-04-27T12:07:12.517256Z","steps":["trace[634741778] 'range keys from in-memory index tree' (duration: 174.132213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:12.517293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.101023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-04-27T12:07:12.517471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.940081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T12:07:12.51738Z","caller":"traceutil/trace.go:171","msg":"trace[1636309100] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1068; }","duration":"174.246966ms","start":"2026-04-27T12:07:12.343122Z","end":"2026-04-27T12:07:12.517369Z","steps":["trace[1636309100] 'range keys from in-memory index tree' (duration: 174.026711ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:12.517551Z","caller":"traceutil/trace.go:171","msg":"trace[1342434018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1068; }","duration":"198.054244ms","start":"2026-04-27T12:07:12.319467Z","end":"2026-04-27T12:07:12.517521Z","steps":["trace[1342434018] 'range keys from in-memory index tree' (duration: 197.346274ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:24.962188Z","caller":"traceutil/trace.go:171","msg":"trace[1518448850] linearizableReadLoop","detail":"{readStateIndex:1203; appliedIndex:1202; }","duration":"383.867548ms","start":"2026-04-27T12:07:24.578294Z","end":"2026-04-27T12:07:24.962161Z","steps":["trace[1518448850] 'read index received' (duration: 383.714935ms)","trace[1518448850] 'applied index is now lower than readState.Index' (duration: 151.243µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:24.962225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.416715Z","time spent":"545.499998ms","remote":"127.0.0.1:52560","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-27T12:07:24.962475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.610934ms","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":"warn","ts":"2026-04-27T12:07:24.962477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.409544ms","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-04-27T12:07:24.962528Z","caller":"traceutil/trace.go:171","msg":"trace[1469344261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1159; }","duration":"331.717797ms","start":"2026-04-27T12:07:24.630796Z","end":"2026-04-27T12:07:24.962513Z","steps":["trace[1469344261] 'agreement among raft nodes before linearized reading' (duration: 331.523842ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:24.96254Z","caller":"traceutil/trace.go:171","msg":"trace[1305718602] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1159; }","duration":"330.483215ms","start":"2026-04-27T12:07:24.632043Z","end":"2026-04-27T12:07:24.962526Z","steps":["trace[1305718602] 'agreement among raft nodes before linearized reading' (duration: 330.336662ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.962568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.472248ms","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-04-27T12:07:24.962579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.630777Z","time spent":"331.788358ms","remote":"127.0.0.1:52834","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-04-27T12:07:24.962597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.632038Z","time spent":"330.547387ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-27T12:07:24.962634Z","caller":"traceutil/trace.go:171","msg":"trace[822245963] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1159; }","duration":"329.602542ms","start":"2026-04-27T12:07:24.633008Z","end":"2026-04-27T12:07:24.962611Z","steps":["trace[822245963] 'agreement among raft nodes before linearized reading' (duration: 329.328035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.962651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.597479ms","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-04-27T12:07:24.962745Z","caller":"traceutil/trace.go:171","msg":"trace[569872623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1159; }","duration":"330.721582ms","start":"2026-04-27T12:07:24.632003Z","end":"2026-04-27T12:07:24.962724Z","steps":["trace[569872623] 'agreement among raft nodes before linearized reading' (duration: 330.421704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.96268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.632982Z","time spent":"329.690125ms","remote":"127.0.0.1:52834","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-04-27T12:07:24.962793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.631996Z","time spent":"330.788624ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-27T12:07:24.963083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.761922ms","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-04-27T12:07:24.963177Z","caller":"traceutil/trace.go:171","msg":"trace[719262029] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1159; }","duration":"384.911665ms","start":"2026-04-27T12:07:24.57825Z","end":"2026-04-27T12:07:24.963162Z","steps":["trace[719262029] 'agreement among raft nodes before linearized reading' (duration: 384.046073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:24.963301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.578234Z","time spent":"385.05315ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:07:25.681592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208908833,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:07:26.067769Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.103968562s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T12:07:26.088129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.927134ms","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-04-27T12:07:26.088256Z","caller":"traceutil/trace.go:171","msg":"trace[1860065687] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1159; }","duration":"824.069017ms","start":"2026-04-27T12:07:25.26417Z","end":"2026-04-27T12:07:26.088239Z","steps":["trace[1860065687] 'range keys from in-memory index tree' (duration: 823.910393ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:26.088641Z","caller":"traceutil/trace.go:171","msg":"trace[747221590] transaction","detail":"{read_only:false; response_revision:1160; number_of_response:1; }","duration":"1.124998637s","start":"2026-04-27T12:07:24.963621Z","end":"2026-04-27T12:07:26.08862Z","steps":["trace[747221590] 'process raft request' (duration: 1.105230295s)","trace[747221590] 'compare' (duration: 19.641188ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:26.088769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.963594Z","time spent":"1.125099209s","remote":"127.0.0.1:52560","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":116,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:26.092255Z","caller":"traceutil/trace.go:171","msg":"trace[1872066162] transaction","detail":"{read_only:false; response_revision:1162; number_of_response:1; }","duration":"1.123617881s","start":"2026-04-27T12:07:24.968624Z","end":"2026-04-27T12:07:26.092242Z","steps":["trace[1872066162] 'process raft request' (duration: 1.12357286s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:26.092366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.968602Z","time spent":"1.123715543s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:26.092437Z","caller":"traceutil/trace.go:171","msg":"trace[96853537] transaction","detail":"{read_only:false; response_revision:1163; number_of_response:1; }","duration":"1.123782865s","start":"2026-04-27T12:07:24.968649Z","end":"2026-04-27T12:07:26.092432Z","steps":["trace[96853537] 'process raft request' (duration: 1.12357234s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:26.092466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.968627Z","time spent":"1.123821786s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:26.092469Z","caller":"traceutil/trace.go:171","msg":"trace[1953546972] transaction","detail":"{read_only:false; response_revision:1164; number_of_response:1; }","duration":"1.123700833s","start":"2026-04-27T12:07:24.968754Z","end":"2026-04-27T12:07:26.092455Z","steps":["trace[1953546972] 'process raft request' (duration: 1.123616551s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:26.09254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.968721Z","time spent":"1.123780785s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:26.0926Z","caller":"traceutil/trace.go:171","msg":"trace[2070721409] transaction","detail":"{read_only:false; response_revision:1165; number_of_response:1; }","duration":"1.123152s","start":"2026-04-27T12:07:24.96944Z","end":"2026-04-27T12:07:26.092592Z","steps":["trace[2070721409] 'process raft request' (duration: 1.122980115s)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:26.092591Z","caller":"traceutil/trace.go:171","msg":"trace[1793911533] linearizableReadLoop","detail":"{readStateIndex:1210; appliedIndex:1203; }","duration":"911.290249ms","start":"2026-04-27T12:07:25.181285Z","end":"2026-04-27T12:07:26.092575Z","steps":["trace[1793911533] 'read index received' (duration: 887.716327ms)","trace[1793911533] 'applied index is now lower than readState.Index' (duration: 23.572912ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:26.092637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.969431Z","time spent":"1.123186191s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:26.09264Z","caller":"traceutil/trace.go:171","msg":"trace[803960147] transaction","detail":"{read_only:false; response_revision:1166; number_of_response:1; }","duration":"672.999253ms","start":"2026-04-27T12:07:25.419631Z","end":"2026-04-27T12:07:26.09263Z","steps":["trace[803960147] 'process raft request' (duration: 672.879759ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:26.092682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:25.419596Z","time spent":"673.062905ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:07:26.093166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.447632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:26.092285Z","caller":"traceutil/trace.go:171","msg":"trace[1520407771] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"1.123649702s","start":"2026-04-27T12:07:24.968612Z","end":"2026-04-27T12:07:26.092262Z","steps":["trace[1520407771] 'process raft request' (duration: 1.123484588s)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:26.093229Z","caller":"traceutil/trace.go:171","msg":"trace[777452193] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1166; }","duration":"911.973675ms","start":"2026-04-27T12:07:25.181245Z","end":"2026-04-27T12:07:26.093218Z","steps":["trace[777452193] 'agreement among raft nodes before linearized reading' (duration: 911.427122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:26.093252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:25.181228Z","time spent":"912.016507ms","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-27T12:07:26.093307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:24.968581Z","time spent":"1.124666549s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:42.424957Z","caller":"traceutil/trace.go:171","msg":"trace[1136394707] linearizableReadLoop","detail":"{readStateIndex:1268; appliedIndex:1267; }","duration":"251.375904ms","start":"2026-04-27T12:07:42.173561Z","end":"2026-04-27T12:07:42.424937Z","steps":["trace[1136394707] 'read index received' (duration: 251.20122ms)","trace[1136394707] 'applied index is now lower than readState.Index' (duration: 174.064µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:42.425018Z","caller":"traceutil/trace.go:171","msg":"trace[1212583826] transaction","detail":"{read_only:false; response_revision:1221; number_of_response:1; }","duration":"255.949593ms","start":"2026-04-27T12:07:42.169029Z","end":"2026-04-27T12:07:42.424978Z","steps":["trace[1212583826] 'process raft request' (duration: 255.782609ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.425116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.53976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-27T12:07:42.425142Z","caller":"traceutil/trace.go:171","msg":"trace[671310724] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1221; }","duration":"251.604401ms","start":"2026-04-27T12:07:42.173531Z","end":"2026-04-27T12:07:42.425136Z","steps":["trace[671310724] 'agreement among raft nodes before linearized reading' (duration: 251.495268ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.42524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.27606ms","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-04-27T12:07:42.425255Z","caller":"traceutil/trace.go:171","msg":"trace[513380856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1221; }","duration":"215.300191ms","start":"2026-04-27T12:07:42.20995Z","end":"2026-04-27T12:07:42.42525Z","steps":["trace[513380856] 'agreement among raft nodes before linearized reading' (duration: 215.25382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.425245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.872926ms","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-04-27T12:07:42.425292Z","caller":"traceutil/trace.go:171","msg":"trace[134066291] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1221; }","duration":"215.934917ms","start":"2026-04-27T12:07:42.209348Z","end":"2026-04-27T12:07:42.425283Z","steps":["trace[134066291] 'agreement among raft nodes before linearized reading' (duration: 215.838895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.425291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.240646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-04-27T12:07:42.425351Z","caller":"traceutil/trace.go:171","msg":"trace[684064321] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1221; }","duration":"223.353188ms","start":"2026-04-27T12:07:42.201983Z","end":"2026-04-27T12:07:42.425337Z","steps":["trace[684064321] 'agreement among raft nodes before linearized reading' (duration: 223.120943ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.425387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.213769ms","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-04-27T12:07:42.425469Z","caller":"traceutil/trace.go:171","msg":"trace[408115899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1221; }","duration":"222.344443ms","start":"2026-04-27T12:07:42.203102Z","end":"2026-04-27T12:07:42.425446Z","steps":["trace[408115899] 'agreement among raft nodes before linearized reading' (duration: 222.087257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:42.699954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.285566ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:07:42.700187Z","caller":"traceutil/trace.go:171","msg":"trace[284133641] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"269.444262ms","start":"2026-04-27T12:07:42.430725Z","end":"2026-04-27T12:07:42.700169Z","steps":["trace[284133641] 'process raft request' (duration: 269.400511ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:42.700235Z","caller":"traceutil/trace.go:171","msg":"trace[1577800246] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"269.511164ms","start":"2026-04-27T12:07:42.430701Z","end":"2026-04-27T12:07:42.700212Z","steps":["trace[1577800246] 'process raft request' (duration: 269.37752ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:42.700218Z","caller":"traceutil/trace.go:171","msg":"trace[152460866] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"269.470802ms","start":"2026-04-27T12:07:42.430707Z","end":"2026-04-27T12:07:42.700178Z","steps":["trace[152460866] 'process raft request' (duration: 138.858148ms)","trace[152460866] 'compare' (duration: 130.144163ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:42.704722Z","caller":"traceutil/trace.go:171","msg":"trace[1573637249] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"273.96294ms","start":"2026-04-27T12:07:42.430742Z","end":"2026-04-27T12:07:42.704705Z","steps":["trace[1573637249] 'process raft request' (duration: 273.327263ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:42.704839Z","caller":"traceutil/trace.go:171","msg":"trace[1693216566] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"270.755487ms","start":"2026-04-27T12:07:42.43407Z","end":"2026-04-27T12:07:42.704825Z","steps":["trace[1693216566] 'process raft request' (duration: 270.371558ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:42.705125Z","caller":"traceutil/trace.go:171","msg":"trace[1816279465] transaction","detail":"{read_only:false; response_revision:1227; number_of_response:1; }","duration":"213.133104ms","start":"2026-04-27T12:07:42.491966Z","end":"2026-04-27T12:07:42.705099Z","steps":["trace[1816279465] 'process raft request' (duration: 212.520028ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.380511Z","caller":"traceutil/trace.go:171","msg":"trace[546472069] linearizableReadLoop","detail":"{readStateIndex:1277; appliedIndex:1276; }","duration":"197.417446ms","start":"2026-04-27T12:07:44.183071Z","end":"2026-04-27T12:07:44.380489Z","steps":["trace[546472069] 'read index received' (duration: 197.056686ms)","trace[546472069] 'applied index is now lower than readState.Index' (duration: 359.93µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:07:44.380571Z","caller":"traceutil/trace.go:171","msg":"trace[1381937089] transaction","detail":"{read_only:false; response_revision:1230; number_of_response:1; }","duration":"932.97608ms","start":"2026-04-27T12:07:43.447563Z","end":"2026-04-27T12:07:44.38054Z","steps":["trace[1381937089] 'process raft request' (duration: 932.763264ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:44.380737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:43.447539Z","time spent":"933.099994ms","remote":"127.0.0.1:52616","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":757,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-27T12:07:44.380728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.626061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:07:44.381005Z","caller":"traceutil/trace.go:171","msg":"trace[751946347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1230; }","duration":"197.925178ms","start":"2026-04-27T12:07:44.183021Z","end":"2026-04-27T12:07:44.380946Z","steps":["trace[751946347] 'agreement among raft nodes before linearized reading' (duration: 197.556369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:44.381242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.240516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-04-27T12:07:44.381297Z","caller":"traceutil/trace.go:171","msg":"trace[684510156] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1230; }","duration":"174.535704ms","start":"2026-04-27T12:07:44.206747Z","end":"2026-04-27T12:07:44.381283Z","steps":["trace[684510156] 'agreement among raft nodes before linearized reading' (duration: 173.860726ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.520431Z","caller":"traceutil/trace.go:171","msg":"trace[1896743361] linearizableReadLoop","detail":"{readStateIndex:1279; appliedIndex:1278; }","duration":"103.753419ms","start":"2026-04-27T12:07:44.416637Z","end":"2026-04-27T12:07:44.52039Z","steps":["trace[1896743361] 'read index received' (duration: 103.476032ms)","trace[1896743361] 'applied index is now lower than readState.Index' (duration: 275.627µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:44.520783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.106299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.5\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-04-27T12:07:44.520829Z","caller":"traceutil/trace.go:171","msg":"trace[1468546636] range","detail":"{range_begin:/registry/masterleases/199.19.213.5; range_end:; response_count:1; response_revision:1232; }","duration":"104.221432ms","start":"2026-04-27T12:07:44.416593Z","end":"2026-04-27T12:07:44.520815Z","steps":["trace[1468546636] 'agreement among raft nodes before linearized reading' (duration: 103.958985ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.521337Z","caller":"traceutil/trace.go:171","msg":"trace[1607827238] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"133.630684ms","start":"2026-04-27T12:07:44.387682Z","end":"2026-04-27T12:07:44.521312Z","steps":["trace[1607827238] 'process raft request' (duration: 132.460023ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.534309Z","caller":"traceutil/trace.go:171","msg":"trace[2104056286] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"103.628206ms","start":"2026-04-27T12:07:44.430662Z","end":"2026-04-27T12:07:44.53429Z","steps":["trace[2104056286] 'process raft request' (duration: 103.478312ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.753336Z","caller":"traceutil/trace.go:171","msg":"trace[1489120507] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"182.801217ms","start":"2026-04-27T12:07:44.57051Z","end":"2026-04-27T12:07:44.753311Z","steps":["trace[1489120507] 'process raft request' (duration: 182.542441ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:44.799622Z","caller":"traceutil/trace.go:171","msg":"trace[1954277868] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"227.428634ms","start":"2026-04-27T12:07:44.572152Z","end":"2026-04-27T12:07:44.799581Z","steps":["trace[1954277868] 'process raft request' (duration: 227.24089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:45.282665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.443471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:07:45.282842Z","caller":"traceutil/trace.go:171","msg":"trace[1427414776] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"478.767377ms","start":"2026-04-27T12:07:44.804052Z","end":"2026-04-27T12:07:45.28282Z","steps":["trace[1427414776] 'process raft request' (duration: 42.053969ms)","trace[1427414776] 'compare' (duration: 436.199585ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:45.282935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:44.804034Z","time spent":"478.859301ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:45.306979Z","caller":"traceutil/trace.go:171","msg":"trace[1075612105] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"502.075502ms","start":"2026-04-27T12:07:44.80488Z","end":"2026-04-27T12:07:45.306955Z","steps":["trace[1075612105] 'process raft request' (duration: 501.781535ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:45.306997Z","caller":"traceutil/trace.go:171","msg":"trace[1408050] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"501.978339ms","start":"2026-04-27T12:07:44.80499Z","end":"2026-04-27T12:07:45.306968Z","steps":["trace[1408050] 'process raft request' (duration: 501.885957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:45.307091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:44.804868Z","time spent":"502.168094ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:45.307214Z","caller":"traceutil/trace.go:171","msg":"trace[840778921] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"502.00761ms","start":"2026-04-27T12:07:44.805189Z","end":"2026-04-27T12:07:45.307196Z","steps":["trace[840778921] 'process raft request' (duration: 501.738913ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:45.307225Z","caller":"traceutil/trace.go:171","msg":"trace[23333932] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1284; }","duration":"259.574806ms","start":"2026-04-27T12:07:45.047635Z","end":"2026-04-27T12:07:45.30721Z","steps":["trace[23333932] 'read index received' (duration: 258.856158ms)","trace[23333932] 'applied index is now lower than readState.Index' (duration: 717.958µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:45.307298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:44.80518Z","time spent":"502.067322ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:45.30732Z","caller":"traceutil/trace.go:171","msg":"trace[1489116813] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"500.45857ms","start":"2026-04-27T12:07:44.806856Z","end":"2026-04-27T12:07:45.307315Z","steps":["trace[1489116813] 'process raft request' (duration: 500.280286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:45.307328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.678139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:07:45.307351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:44.806829Z","time spent":"500.504531ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:45.307369Z","caller":"traceutil/trace.go:171","msg":"trace[1473291073] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1240; }","duration":"259.750012ms","start":"2026-04-27T12:07:45.047606Z","end":"2026-04-27T12:07:45.307356Z","steps":["trace[1473291073] 'agreement among raft nodes before linearized reading' (duration: 259.660089ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:45.307995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:44.804982Z","time spent":"502.099302ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:07:49.572686Z","caller":"traceutil/trace.go:171","msg":"trace[131759169] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"184.792229ms","start":"2026-04-27T12:07:49.387868Z","end":"2026-04-27T12:07:49.572661Z","steps":["trace[131759169] 'process raft request' (duration: 178.865216ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:49.572711Z","caller":"traceutil/trace.go:171","msg":"trace[27659246] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"184.622226ms","start":"2026-04-27T12:07:49.388069Z","end":"2026-04-27T12:07:49.572691Z","steps":["trace[27659246] 'process raft request' (duration: 184.569724ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:49.572789Z","caller":"traceutil/trace.go:171","msg":"trace[1941899479] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"184.83838ms","start":"2026-04-27T12:07:49.387914Z","end":"2026-04-27T12:07:49.572752Z","steps":["trace[1941899479] 'process raft request' (duration: 184.696087ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:58.950697Z","caller":"traceutil/trace.go:171","msg":"trace[558570054] linearizableReadLoop","detail":"{readStateIndex:1384; appliedIndex:1383; }","duration":"235.990663ms","start":"2026-04-27T12:07:58.714689Z","end":"2026-04-27T12:07:58.950679Z","steps":["trace[558570054] 'read index received' (duration: 235.856359ms)","trace[558570054] 'applied index is now lower than readState.Index' (duration: 133.644µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:07:58.950852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.145767ms","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-04-27T12:07:58.950889Z","caller":"traceutil/trace.go:171","msg":"trace[1389513366] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1334; }","duration":"236.23858ms","start":"2026-04-27T12:07:58.714643Z","end":"2026-04-27T12:07:58.950881Z","steps":["trace[1389513366] 'agreement among raft nodes before linearized reading' (duration: 236.103505ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:07:58.951135Z","caller":"traceutil/trace.go:171","msg":"trace[797965718] transaction","detail":"{read_only:false; response_revision:1334; number_of_response:1; }","duration":"371.49331ms","start":"2026-04-27T12:07:58.579611Z","end":"2026-04-27T12:07:58.951104Z","steps":["trace[797965718] 'process raft request' (duration: 370.970026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:07:58.951277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:58.579583Z","time spent":"371.623114ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:07:59.681072Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2120524511208909191,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-27T12:08:00.144415Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.173920307s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-27T12:08:00.45315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.346416ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:00.453267Z","caller":"traceutil/trace.go:171","msg":"trace[1744835520] linearizableReadLoop","detail":"{readStateIndex:1385; appliedIndex:1384; }","duration":"1.27270502s","start":"2026-04-27T12:07:59.180543Z","end":"2026-04-27T12:08:00.453248Z","steps":["trace[1744835520] 'read index received' (duration: 964.124487ms)","trace[1744835520] 'applied index is now lower than readState.Index' (duration: 308.579083ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:00.453335Z","caller":"traceutil/trace.go:171","msg":"trace[959904853] transaction","detail":"{read_only:false; response_revision:1335; number_of_response:1; }","duration":"1.482914181s","start":"2026-04-27T12:07:58.970409Z","end":"2026-04-27T12:08:00.453323Z","steps":["trace[959904853] 'process raft request' (duration: 1.174315208s)","trace[959904853] 'compare' (duration: 308.043829ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:00.453401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:58.970389Z","time spent":"1.482977312s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:08:00.453512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.272967586s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:08:00.453533Z","caller":"traceutil/trace.go:171","msg":"trace[750441845] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1335; }","duration":"1.273012207s","start":"2026-04-27T12:07:59.180516Z","end":"2026-04-27T12:08:00.453528Z","steps":["trace[750441845] 'agreement among raft nodes before linearized reading' (duration: 1.272970386s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.45355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.180502Z","time spent":"1.273044688s","remote":"127.0.0.1:52548","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-27T12:08:00.695042Z","caller":"traceutil/trace.go:171","msg":"trace[628108301] linearizableReadLoop","detail":"{readStateIndex:1387; appliedIndex:1385; }","duration":"241.729072ms","start":"2026-04-27T12:08:00.453294Z","end":"2026-04-27T12:08:00.695023Z","steps":["trace[628108301] 'read index received' (duration: 222.927103ms)","trace[628108301] 'applied index is now lower than readState.Index' (duration: 18.799329ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:00.69522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.849314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-27T12:08:00.695149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.446361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-27T12:08:00.695252Z","caller":"traceutil/trace.go:171","msg":"trace[640300617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1336; }","duration":"239.914415ms","start":"2026-04-27T12:08:00.455331Z","end":"2026-04-27T12:08:00.695245Z","steps":["trace[640300617] 'agreement among raft nodes before linearized reading' (duration: 239.861983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079964452s","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-04-27T12:08:00.695377Z","caller":"traceutil/trace.go:171","msg":"trace[1434345888] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1336; }","duration":"844.725208ms","start":"2026-04-27T12:07:59.850634Z","end":"2026-04-27T12:08:00.695359Z","steps":["trace[1434345888] 'agreement among raft nodes before linearized reading' (duration: 844.39607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255393439s","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":"warn","ts":"2026-04-27T12:08:00.695444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079875821s","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-04-27T12:08:00.695427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.850615Z","time spent":"844.798222ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":30,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"info","ts":"2026-04-27T12:08:00.695458Z","caller":"traceutil/trace.go:171","msg":"trace[580763419] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.255676768s","start":"2026-04-27T12:07:59.439773Z","end":"2026-04-27T12:08:00.69545Z","steps":["trace[580763419] 'agreement among raft nodes before linearized reading' (duration: 1.255357649s)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:00.695468Z","caller":"traceutil/trace.go:171","msg":"trace[355000181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.079933203s","start":"2026-04-27T12:07:59.615528Z","end":"2026-04-27T12:08:00.695461Z","steps":["trace[355000181] 'agreement among raft nodes before linearized reading' (duration: 1.079647695s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.615507Z","time spent":"1.079976443s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-27T12:08:00.695502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.439758Z","time spent":"1.255723358s","remote":"127.0.0.1:52834","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-04-27T12:08:00.695564Z","caller":"traceutil/trace.go:171","msg":"trace[1827227239] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1336; }","duration":"1.080039434s","start":"2026-04-27T12:07:59.615356Z","end":"2026-04-27T12:08:00.695396Z","steps":["trace[1827227239] 'agreement among raft nodes before linearized reading' (duration: 1.079773038s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.080075506s","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-04-27T12:08:00.695582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.615334Z","time spent":"1.08024357s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-27T12:08:00.695589Z","caller":"traceutil/trace.go:171","msg":"trace[47033540] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1336; }","duration":"1.080099177s","start":"2026-04-27T12:07:59.615484Z","end":"2026-04-27T12:08:00.695583Z","steps":["trace[47033540] 'agreement among raft nodes before linearized reading' (duration: 1.079711996s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.615434Z","time spent":"1.080173628s","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-27T12:08:00.695652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.60485ms","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:530"} {"level":"info","ts":"2026-04-27T12:08:00.695671Z","caller":"traceutil/trace.go:171","msg":"trace[53370029] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1336; }","duration":"440.652261ms","start":"2026-04-27T12:08:00.255012Z","end":"2026-04-27T12:08:00.695664Z","steps":["trace[53370029] 'agreement among raft nodes before linearized reading' (duration: 440.161558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:00.254994Z","time spent":"440.688342ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":553,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-04-27T12:08:00.695739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.261590071s","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-04-27T12:08:00.695763Z","caller":"traceutil/trace.go:171","msg":"trace[1014420754] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1336; }","duration":"1.261614561s","start":"2026-04-27T12:07:59.434141Z","end":"2026-04-27T12:08:00.695756Z","steps":["trace[1014420754] 'agreement among raft nodes before linearized reading' (duration: 1.261067677s)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.695785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.434076Z","time spent":"1.261702103s","remote":"127.0.0.1:52834","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-04-27T12:08:00.695926Z","caller":"traceutil/trace.go:171","msg":"trace[1549818775] transaction","detail":"{read_only:false; response_revision:1336; number_of_response:1; }","duration":"706.295036ms","start":"2026-04-27T12:07:59.988819Z","end":"2026-04-27T12:08:00.695114Z","steps":["trace[1549818775] 'process raft request' (duration: 706.100472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:00.696062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:07:59.988796Z","time spent":"707.192119ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-27T12:08:25.010494Z","caller":"traceutil/trace.go:171","msg":"trace[709296086] transaction","detail":"{read_only:false; response_revision:1451; number_of_response:1; }","duration":"169.827131ms","start":"2026-04-27T12:08:24.840639Z","end":"2026-04-27T12:08:25.010466Z","steps":["trace[709296086] 'process raft request' (duration: 89.819558ms)","trace[709296086] 'compare' (duration: 79.819468ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:25.010784Z","caller":"traceutil/trace.go:171","msg":"trace[595873885] transaction","detail":"{read_only:false; response_revision:1452; number_of_response:1; }","duration":"169.597375ms","start":"2026-04-27T12:08:24.841159Z","end":"2026-04-27T12:08:25.010757Z","steps":["trace[595873885] 'process raft request' (duration: 169.257966ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:25.010887Z","caller":"traceutil/trace.go:171","msg":"trace[1012573638] linearizableReadLoop","detail":"{readStateIndex:1510; appliedIndex:1506; }","duration":"119.920741ms","start":"2026-04-27T12:08:24.890949Z","end":"2026-04-27T12:08:25.01087Z","steps":["trace[1012573638] 'read index received' (duration: 39.421966ms)","trace[1012573638] 'applied index is now lower than readState.Index' (duration: 80.497755ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:25.010968Z","caller":"traceutil/trace.go:171","msg":"trace[1937803652] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"166.7187ms","start":"2026-04-27T12:08:24.844239Z","end":"2026-04-27T12:08:25.010957Z","steps":["trace[1937803652] 'process raft request' (duration: 166.584356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:25.011018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.032344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-27T12:08:25.011004Z","caller":"traceutil/trace.go:171","msg":"trace[778194045] transaction","detail":"{read_only:false; response_revision:1453; number_of_response:1; }","duration":"167.467649ms","start":"2026-04-27T12:08:24.843501Z","end":"2026-04-27T12:08:25.010968Z","steps":["trace[778194045] 'process raft request' (duration: 167.146631ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:25.011064Z","caller":"traceutil/trace.go:171","msg":"trace[1440614600] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1454; }","duration":"120.156117ms","start":"2026-04-27T12:08:24.890895Z","end":"2026-04-27T12:08:25.011051Z","steps":["trace[1440614600] 'agreement among raft nodes before linearized reading' (duration: 120.047874ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:29.266978Z","caller":"traceutil/trace.go:171","msg":"trace[960838968] transaction","detail":"{read_only:false; response_revision:1471; number_of_response:1; }","duration":"232.923412ms","start":"2026-04-27T12:08:29.034028Z","end":"2026-04-27T12:08:29.266952Z","steps":["trace[960838968] 'process raft request' (duration: 232.84822ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:29.267209Z","caller":"traceutil/trace.go:171","msg":"trace[822736716] transaction","detail":"{read_only:false; response_revision:1470; number_of_response:1; }","duration":"233.242611ms","start":"2026-04-27T12:08:29.033941Z","end":"2026-04-27T12:08:29.267184Z","steps":["trace[822736716] 'process raft request' (duration: 232.597314ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:29.267198Z","caller":"traceutil/trace.go:171","msg":"trace[320433074] linearizableReadLoop","detail":"{readStateIndex:1527; appliedIndex:1524; }","duration":"231.813264ms","start":"2026-04-27T12:08:29.035356Z","end":"2026-04-27T12:08:29.267169Z","steps":["trace[320433074] 'read index received' (duration: 46.750236ms)","trace[320433074] 'applied index is now lower than readState.Index' (duration: 185.061098ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:29.267241Z","caller":"traceutil/trace.go:171","msg":"trace[1708119197] transaction","detail":"{read_only:false; response_revision:1472; number_of_response:1; }","duration":"185.936151ms","start":"2026-04-27T12:08:29.081283Z","end":"2026-04-27T12:08:29.267219Z","steps":["trace[1708119197] 'process raft request' (duration: 185.637393ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:29.267378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.013959ms","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:530"} {"level":"info","ts":"2026-04-27T12:08:29.267421Z","caller":"traceutil/trace.go:171","msg":"trace[756603259] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1472; }","duration":"232.102882ms","start":"2026-04-27T12:08:29.035306Z","end":"2026-04-27T12:08:29.267409Z","steps":["trace[756603259] 'agreement among raft nodes before linearized reading' (duration: 231.958728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:29.503111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.2917ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:29.503249Z","caller":"traceutil/trace.go:171","msg":"trace[9612693] transaction","detail":"{read_only:false; response_revision:1473; number_of_response:1; }","duration":"229.605036ms","start":"2026-04-27T12:08:29.273624Z","end":"2026-04-27T12:08:29.503229Z","steps":["trace[9612693] 'process raft request' (duration: 126.085171ms)","trace[9612693] 'compare' (duration: 103.136875ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:32.446465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.182772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:32.446649Z","caller":"traceutil/trace.go:171","msg":"trace[1472292526] transaction","detail":"{read_only:false; response_revision:1485; number_of_response:1; }","duration":"236.136407ms","start":"2026-04-27T12:08:32.210433Z","end":"2026-04-27T12:08:32.44657Z","steps":["trace[1472292526] 'process raft request' (duration: 116.75883ms)","trace[1472292526] 'compare' (duration: 119.023578ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:33.025519Z","caller":"traceutil/trace.go:171","msg":"trace[180546172] linearizableReadLoop","detail":"{readStateIndex:1543; appliedIndex:1542; }","duration":"116.272267ms","start":"2026-04-27T12:08:32.909221Z","end":"2026-04-27T12:08:33.025493Z","steps":["trace[180546172] 'read index received' (duration: 116.127243ms)","trace[180546172] 'applied index is now lower than readState.Index' (duration: 143.744µs)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:33.025724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.485432ms","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-04-27T12:08:33.025719Z","caller":"traceutil/trace.go:171","msg":"trace[1734669400] transaction","detail":"{read_only:false; response_revision:1486; number_of_response:1; }","duration":"123.333601ms","start":"2026-04-27T12:08:32.902357Z","end":"2026-04-27T12:08:33.025691Z","steps":["trace[1734669400] 'process raft request' (duration: 122.991442ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:33.025765Z","caller":"traceutil/trace.go:171","msg":"trace[1821640799] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1486; }","duration":"116.570464ms","start":"2026-04-27T12:08:32.909183Z","end":"2026-04-27T12:08:33.025753Z","steps":["trace[1821640799] 'agreement among raft nodes before linearized reading' (duration: 116.39955ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:33.173214Z","caller":"traceutil/trace.go:171","msg":"trace[841840893] transaction","detail":"{read_only:false; response_revision:1487; number_of_response:1; }","duration":"144.196093ms","start":"2026-04-27T12:08:33.028985Z","end":"2026-04-27T12:08:33.173181Z","steps":["trace[841840893] 'process raft request' (duration: 76.596054ms)","trace[841840893] 'compare' (duration: 67.352083ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:33.517144Z","caller":"traceutil/trace.go:171","msg":"trace[547209450] transaction","detail":"{read_only:false; response_revision:1492; number_of_response:1; }","duration":"130.813185ms","start":"2026-04-27T12:08:33.386307Z","end":"2026-04-27T12:08:33.51712Z","steps":["trace[547209450] 'process raft request' (duration: 130.168408ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:08:33.738944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.155974ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:33.739124Z","caller":"traceutil/trace.go:171","msg":"trace[368271288] transaction","detail":"{read_only:false; response_revision:1493; number_of_response:1; }","duration":"140.269821ms","start":"2026-04-27T12:08:33.598827Z","end":"2026-04-27T12:08:33.739097Z","steps":["trace[368271288] 'process raft request' (duration: 33.8163ms)","trace[368271288] 'compare' (duration: 105.942588ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:35.559708Z","caller":"traceutil/trace.go:171","msg":"trace[1329427827] transaction","detail":"{read_only:false; response_revision:1500; number_of_response:1; }","duration":"182.542632ms","start":"2026-04-27T12:08:35.377136Z","end":"2026-04-27T12:08:35.559679Z","steps":["trace[1329427827] 'process raft request' (duration: 92.665793ms)","trace[1329427827] 'compare' (duration: 89.759326ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:36.143539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.768712ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:08:36.143858Z","caller":"traceutil/trace.go:171","msg":"trace[1984933220] transaction","detail":"{read_only:false; response_revision:1503; number_of_response:1; }","duration":"304.414515ms","start":"2026-04-27T12:08:35.839417Z","end":"2026-04-27T12:08:36.143831Z","steps":["trace[1984933220] 'process raft request' (duration: 49.234132ms)","trace[1984933220] 'compare' (duration: 254.548366ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:36.143948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-27T12:08:35.839398Z","time spent":"304.507656ms","remote":"127.0.0.1:52834","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-27T12:08:37.321411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.128084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-04-27T12:08:37.321476Z","caller":"traceutil/trace.go:171","msg":"trace[586316978] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1504; }","duration":"108.259967ms","start":"2026-04-27T12:08:37.213202Z","end":"2026-04-27T12:08:37.321462Z","steps":["trace[586316978] 'range keys from in-memory index tree' (duration: 107.889319ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:37.446656Z","caller":"traceutil/trace.go:171","msg":"trace[1404163834] transaction","detail":"{read_only:false; response_revision:1506; number_of_response:1; }","duration":"120.937498ms","start":"2026-04-27T12:08:37.325636Z","end":"2026-04-27T12:08:37.446574Z","steps":["trace[1404163834] 'process raft request' (duration: 118.076324ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:37.447467Z","caller":"traceutil/trace.go:171","msg":"trace[304326363] transaction","detail":"{read_only:false; response_revision:1507; number_of_response:1; }","duration":"121.274836ms","start":"2026-04-27T12:08:37.326155Z","end":"2026-04-27T12:08:37.447429Z","steps":["trace[304326363] 'process raft request' (duration: 120.367043ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:37.602185Z","caller":"traceutil/trace.go:171","msg":"trace[1940227055] transaction","detail":"{read_only:false; response_revision:1509; number_of_response:1; }","duration":"146.962766ms","start":"2026-04-27T12:08:37.455182Z","end":"2026-04-27T12:08:37.602144Z","steps":["trace[1940227055] 'process raft request' (duration: 110.861066ms)","trace[1940227055] 'compare' (duration: 35.931345ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:37.604694Z","caller":"traceutil/trace.go:171","msg":"trace[791397533] transaction","detail":"{read_only:false; response_revision:1510; number_of_response:1; }","duration":"146.147435ms","start":"2026-04-27T12:08:37.458526Z","end":"2026-04-27T12:08:37.604673Z","steps":["trace[791397533] 'process raft request' (duration: 145.686353ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.61391Z","caller":"traceutil/trace.go:171","msg":"trace[2021973174] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"159.736078ms","start":"2026-04-27T12:08:39.454129Z","end":"2026-04-27T12:08:39.613865Z","steps":["trace[2021973174] 'process raft request' (duration: 146.091282ms)","trace[2021973174] 'compare' (duration: 13.377128ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:39.613995Z","caller":"traceutil/trace.go:171","msg":"trace[1140838292] linearizableReadLoop","detail":"{readStateIndex:1573; appliedIndex:1572; }","duration":"159.42197ms","start":"2026-04-27T12:08:39.454317Z","end":"2026-04-27T12:08:39.613739Z","steps":["trace[1140838292] 'read index received' (duration: 145.968689ms)","trace[1140838292] 'applied index is now lower than readState.Index' (duration: 13.451861ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:39.61417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.827111ms","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-04-27T12:08:39.61426Z","caller":"traceutil/trace.go:171","msg":"trace[1243978952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1515; }","duration":"159.958853ms","start":"2026-04-27T12:08:39.454284Z","end":"2026-04-27T12:08:39.614243Z","steps":["trace[1243978952] 'agreement among raft nodes before linearized reading' (duration: 159.682276ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:08:39.783311Z","caller":"traceutil/trace.go:171","msg":"trace[65543893] linearizableReadLoop","detail":"{readStateIndex:1575; appliedIndex:1574; }","duration":"145.721583ms","start":"2026-04-27T12:08:39.637565Z","end":"2026-04-27T12:08:39.783286Z","steps":["trace[65543893] 'read index received' (duration: 29.887167ms)","trace[65543893] 'applied index is now lower than readState.Index' (duration: 115.833256ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:08:39.783398Z","caller":"traceutil/trace.go:171","msg":"trace[1630575043] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"163.228839ms","start":"2026-04-27T12:08:39.620131Z","end":"2026-04-27T12:08:39.78336Z","steps":["trace[1630575043] 'process raft request' (duration: 117.886438ms)","trace[1630575043] 'compare' (duration: 45.144766ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:08:39.78352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.935779ms","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-04-27T12:08:39.783556Z","caller":"traceutil/trace.go:171","msg":"trace[1030262660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1517; }","duration":"146.02224ms","start":"2026-04-27T12:08:39.637523Z","end":"2026-04-27T12:08:39.783546Z","steps":["trace[1030262660] 'agreement among raft nodes before linearized reading' (duration: 145.860256ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:09:56.328797Z","caller":"traceutil/trace.go:171","msg":"trace[2057037032] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"156.428874ms","start":"2026-04-27T12:09:56.17233Z","end":"2026-04-27T12:09:56.328759Z","steps":["trace[2057037032] 'process raft request' (duration: 154.760389ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:03.54861Z","caller":"traceutil/trace.go:171","msg":"trace[350955853] transaction","detail":"{read_only:false; response_revision:1900; number_of_response:1; }","duration":"218.409812ms","start":"2026-04-27T12:10:03.330175Z","end":"2026-04-27T12:10:03.548585Z","steps":["trace[350955853] 'process raft request' (duration: 218.226308ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:03.738312Z","caller":"traceutil/trace.go:171","msg":"trace[1050111070] transaction","detail":"{read_only:false; response_revision:1901; number_of_response:1; }","duration":"149.091981ms","start":"2026-04-27T12:10:03.589195Z","end":"2026-04-27T12:10:03.738287Z","steps":["trace[1050111070] 'process raft request' (duration: 148.061914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:10:04.216057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.923169ms","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-04-27T12:10:04.216133Z","caller":"traceutil/trace.go:171","msg":"trace[65143362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1903; }","duration":"135.071253ms","start":"2026-04-27T12:10:04.081044Z","end":"2026-04-27T12:10:04.216115Z","steps":["trace[65143362] 'range keys from in-memory index tree' (duration: 134.695664ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:10.946979Z","caller":"traceutil/trace.go:171","msg":"trace[2014672676] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"127.658549ms","start":"2026-04-27T12:10:10.819285Z","end":"2026-04-27T12:10:10.946943Z","steps":["trace[2014672676] 'process raft request' (duration: 127.477534ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:16.856438Z","caller":"traceutil/trace.go:171","msg":"trace[704120584] linearizableReadLoop","detail":"{readStateIndex:2041; appliedIndex:2040; }","duration":"251.249132ms","start":"2026-04-27T12:10:16.605168Z","end":"2026-04-27T12:10:16.856417Z","steps":["trace[704120584] 'read index received' (duration: 188.07576ms)","trace[704120584] 'applied index is now lower than readState.Index' (duration: 63.172262ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:10:16.856462Z","caller":"traceutil/trace.go:171","msg":"trace[1866282210] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"251.930669ms","start":"2026-04-27T12:10:16.604505Z","end":"2026-04-27T12:10:16.856436Z","steps":["trace[1866282210] 'process raft request' (duration: 188.799218ms)","trace[1866282210] 'compare' (duration: 62.881555ms)"],"step_count":2} {"level":"warn","ts":"2026-04-27T12:10:16.856599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.418446ms","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:531"} {"level":"info","ts":"2026-04-27T12:10:16.856629Z","caller":"traceutil/trace.go:171","msg":"trace[1601326660] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1963; }","duration":"251.493838ms","start":"2026-04-27T12:10:16.605126Z","end":"2026-04-27T12:10:16.85662Z","steps":["trace[1601326660] 'agreement among raft nodes before linearized reading' (duration: 251.362305ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:22.180785Z","caller":"traceutil/trace.go:171","msg":"trace[1828259543] transaction","detail":"{read_only:false; response_revision:1985; number_of_response:1; }","duration":"129.675451ms","start":"2026-04-27T12:10:22.051088Z","end":"2026-04-27T12:10:22.180763Z","steps":["trace[1828259543] 'process raft request' (duration: 129.399565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:10:23.271412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.595843ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-27T12:10:23.271744Z","caller":"traceutil/trace.go:171","msg":"trace[1653941605] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"286.885063ms","start":"2026-04-27T12:10:22.984827Z","end":"2026-04-27T12:10:23.271712Z","steps":["trace[1653941605] 'process raft request' (duration: 170.863109ms)","trace[1653941605] 'compare' (duration: 115.07996ms)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:10:39.628473Z","caller":"traceutil/trace.go:171","msg":"trace[1846794885] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"120.622345ms","start":"2026-04-27T12:10:39.507815Z","end":"2026-04-27T12:10:39.628437Z","steps":["trace[1846794885] 'process raft request' (duration: 120.390849ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:42.159513Z","caller":"traceutil/trace.go:171","msg":"trace[1885764993] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"108.753745ms","start":"2026-04-27T12:10:42.050731Z","end":"2026-04-27T12:10:42.159485Z","steps":["trace[1885764993] 'process raft request' (duration: 107.589865ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:43.104006Z","caller":"traceutil/trace.go:171","msg":"trace[674069266] transaction","detail":"{read_only:false; response_revision:2080; number_of_response:1; }","duration":"111.061024ms","start":"2026-04-27T12:10:42.992912Z","end":"2026-04-27T12:10:43.103973Z","steps":["trace[674069266] 'process raft request' (duration: 110.8888ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:10:43.111068Z","caller":"traceutil/trace.go:171","msg":"trace[274417476] transaction","detail":"{read_only:false; response_revision:2081; number_of_response:1; }","duration":"118.087309ms","start":"2026-04-27T12:10:42.992959Z","end":"2026-04-27T12:10:43.111047Z","steps":["trace[274417476] 'process raft request' (duration: 117.975315ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:11:13.620152Z","caller":"traceutil/trace.go:171","msg":"trace[1067317180] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"142.285551ms","start":"2026-04-27T12:11:13.477814Z","end":"2026-04-27T12:11:13.6201Z","steps":["trace[1067317180] 'process raft request' (duration: 142.094887ms)"],"step_count":1} {"level":"info","ts":"2026-04-27T12:11:19.138559Z","caller":"traceutil/trace.go:171","msg":"trace[1415833116] linearizableReadLoop","detail":"{readStateIndex:2360; appliedIndex:2359; }","duration":"133.035928ms","start":"2026-04-27T12:11:19.005478Z","end":"2026-04-27T12:11:19.138514Z","steps":["trace[1415833116] 'read index received' (duration: 132.689599ms)","trace[1415833116] 'applied index is now lower than readState.Index' (duration: 345.129µs)"],"step_count":2} {"level":"info","ts":"2026-04-27T12:11:19.138703Z","caller":"traceutil/trace.go:171","msg":"trace[835142001] transaction","detail":"{read_only:false; response_revision:2269; number_of_response:1; }","duration":"173.396859ms","start":"2026-04-27T12:11:18.965264Z","end":"2026-04-27T12:11:19.138661Z","steps":["trace[835142001] 'process raft request' (duration: 172.995358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-27T12:11:19.13885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.365316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-27T12:11:19.138914Z","caller":"traceutil/trace.go:171","msg":"trace[42298873] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2269; }","duration":"133.49619ms","start":"2026-04-27T12:11:19.005405Z","end":"2026-04-27T12:11:19.138901Z","steps":["trace[42298873] 'agreement among raft nodes before linearized reading' (duration: 133.302375ms)"],"step_count":1}