{"level":"warn","ts":"2026-08-22T10:32:50.941716Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:32:50.941802Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105: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.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105: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-08-22T10:32:50.941902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-22T10:32:50.941912Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-22T10:32:50.941928Z","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-08-22T10:32:50.942569Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-08-22T10:32:50.942644Z","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.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105: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-08-22T10:32:50.94895Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.020908ms"} {"level":"info","ts":"2026-08-22T10:32:50.957596Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-08-22T10:32:50.957666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T10:32:50.957688Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-08-22T10:32:50.9577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T10:32:50.957709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-08-22T10:32:50.958Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-08-22T10:32:50.966103Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T10:32:50.968382Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T10:32:50.969447Z","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-08-22T10:32:50.972371Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T10:32:50.972783Z","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-08-22T10:32:50.972872Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T10:32:50.974923Z","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-08-22T10:32:50.974967Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-08-22T10:32:50.974998Z","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-08-22T10:32:50.975162Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-22T10:32:50.979611Z","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-08-22T10:32:50.979776Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-22T10:32:50.979852Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-22T10:32:50.980076Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T10:32:50.980113Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T10:32:51.358715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T10:32:51.358789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T10:32:51.35884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-08-22T10:32:51.358862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-08-22T10:32:51.358874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-22T10:32:51.35889Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-08-22T10:32:51.358912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-22T10:32:51.39819Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:51.400608Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T10:32:51.401107Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:51.401185Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T10:32:51.401388Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T10:32:51.401462Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T10:32:51.401998Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:51.402082Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:51.402112Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T10:32:51.405213Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"info","ts":"2026-08-22T10:32:51.407075Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T10:33:15.102052Z","caller":"traceutil/trace.go:171","msg":"trace[345744563] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"204.366945ms","start":"2026-08-22T10:33:14.897662Z","end":"2026-08-22T10:33:15.102029Z","steps":["trace[345744563] 'process raft request' (duration: 204.208382ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:18.965909Z","caller":"traceutil/trace.go:171","msg":"trace[1582287871] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"104.589034ms","start":"2026-08-22T10:33:18.861294Z","end":"2026-08-22T10:33:18.965883Z","steps":["trace[1582287871] 'process raft request' (duration: 30.675713ms)","trace[1582287871] 'compare' (duration: 73.804848ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:18.966638Z","caller":"traceutil/trace.go:171","msg":"trace[577793844] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"104.537252ms","start":"2026-08-22T10:33:18.862083Z","end":"2026-08-22T10:33:18.96662Z","steps":["trace[577793844] 'process raft request' (duration: 104.39926ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:18.966779Z","caller":"traceutil/trace.go:171","msg":"trace[45921864] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:425; }","duration":"103.197481ms","start":"2026-08-22T10:33:18.863561Z","end":"2026-08-22T10:33:18.966759Z","steps":["trace[45921864] 'read index received' (duration: 28.379905ms)","trace[45921864] 'applied index is now lower than readState.Index' (duration: 74.816146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:18.966901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.367163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-08-22T10:33:18.966912Z","caller":"traceutil/trace.go:171","msg":"trace[373916685] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"101.97074ms","start":"2026-08-22T10:33:18.864889Z","end":"2026-08-22T10:33:18.96686Z","steps":["trace[373916685] 'process raft request' (duration: 101.697366ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:18.966969Z","caller":"traceutil/trace.go:171","msg":"trace[1942753440] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:412; }","duration":"103.476555ms","start":"2026-08-22T10:33:18.86348Z","end":"2026-08-22T10:33:18.966956Z","steps":["trace[1942753440] 'agreement among raft nodes before linearized reading' (duration: 103.347553ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.144625Z","caller":"traceutil/trace.go:171","msg":"trace[449733316] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"172.048104ms","start":"2026-08-22T10:33:18.972555Z","end":"2026-08-22T10:33:19.144603Z","steps":["trace[449733316] 'process raft request' (duration: 171.76184ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.144854Z","caller":"traceutil/trace.go:171","msg":"trace[1844630830] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"172.343419ms","start":"2026-08-22T10:33:18.972495Z","end":"2026-08-22T10:33:19.144838Z","steps":["trace[1844630830] 'process raft request' (duration: 114.488078ms)","trace[1844630830] 'compare' (duration: 57.18504ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:19.145069Z","caller":"traceutil/trace.go:171","msg":"trace[818470741] transaction","detail":"{read_only:false; number_of_response:1; response_revision:415; }","duration":"130.896721ms","start":"2026-08-22T10:33:19.014131Z","end":"2026-08-22T10:33:19.145028Z","steps":["trace[818470741] 'process raft request' (duration: 130.244791ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.385123Z","caller":"traceutil/trace.go:171","msg":"trace[1128594678] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"152.33756ms","start":"2026-08-22T10:33:19.232755Z","end":"2026-08-22T10:33:19.385093Z","steps":["trace[1128594678] 'process raft request' (duration: 83.304476ms)","trace[1128594678] 'compare' (duration: 68.7785ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:19.385223Z","caller":"traceutil/trace.go:171","msg":"trace[2067836413] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"152.360491ms","start":"2026-08-22T10:33:19.232837Z","end":"2026-08-22T10:33:19.385197Z","steps":["trace[2067836413] 'process raft request' (duration: 152.168387ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.38561Z","caller":"traceutil/trace.go:171","msg":"trace[589654136] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"152.440342ms","start":"2026-08-22T10:33:19.233151Z","end":"2026-08-22T10:33:19.385592Z","steps":["trace[589654136] 'process raft request' (duration: 151.960394ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.385734Z","caller":"traceutil/trace.go:171","msg":"trace[1952115511] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"152.436032ms","start":"2026-08-22T10:33:19.233287Z","end":"2026-08-22T10:33:19.385723Z","steps":["trace[1952115511] 'process raft request' (duration: 152.112327ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:19.385847Z","caller":"traceutil/trace.go:171","msg":"trace[1988775962] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"152.370701ms","start":"2026-08-22T10:33:19.233466Z","end":"2026-08-22T10:33:19.385837Z","steps":["trace[1988775962] 'process raft request' (duration: 152.184588ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.007649Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922128133100147290,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T10:33:33.343412Z","caller":"traceutil/trace.go:171","msg":"trace[1529381050] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"960.679161ms","start":"2026-08-22T10:33:32.382708Z","end":"2026-08-22T10:33:33.343387Z","steps":["trace[1529381050] 'process raft request' (duration: 960.533449ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.34405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.38268Z","time spent":"960.817714ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:33:33.353743Z","caller":"traceutil/trace.go:171","msg":"trace[2559392] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"964.349177ms","start":"2026-08-22T10:33:32.389374Z","end":"2026-08-22T10:33:33.353723Z","steps":["trace[2559392] 'process raft request' (duration: 964.226894ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:33.353721Z","caller":"traceutil/trace.go:171","msg":"trace[137662984] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"970.071655ms","start":"2026-08-22T10:33:32.383621Z","end":"2026-08-22T10:33:33.353693Z","steps":["trace[137662984] 'process raft request' (duration: 969.890531ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.353821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.389358Z","time spent":"964.426688ms","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:33.353991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.383598Z","time spent":"970.202716ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354101Z","caller":"traceutil/trace.go:171","msg":"trace[247942107] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"941.575259ms","start":"2026-08-22T10:33:32.412512Z","end":"2026-08-22T10:33:33.354087Z","steps":["trace[247942107] 'process raft request' (duration: 941.289725ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:33.354158Z","caller":"traceutil/trace.go:171","msg":"trace[1225070089] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"964.631201ms","start":"2026-08-22T10:33:32.389516Z","end":"2026-08-22T10:33:33.354147Z","steps":["trace[1225070089] 'process raft request' (duration: 964.115444ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.412497Z","time spent":"941.654781ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354092Z","caller":"traceutil/trace.go:171","msg":"trace[1226495787] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"944.077608ms","start":"2026-08-22T10:33:32.409993Z","end":"2026-08-22T10:33:33.354071Z","steps":["trace[1226495787] 'process raft request' (duration: 943.733243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.389511Z","time spent":"964.670422ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:33:33.354192Z","caller":"traceutil/trace.go:171","msg":"trace[218361110] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"944.100079ms","start":"2026-08-22T10:33:32.410073Z","end":"2026-08-22T10:33:33.354173Z","steps":["trace[218361110] 'process raft request' (duration: 943.695923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.409969Z","time spent":"944.273021ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354326Z","caller":"traceutil/trace.go:171","msg":"trace[1534552217] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"939.351217ms","start":"2026-08-22T10:33:32.41496Z","end":"2026-08-22T10:33:33.354311Z","steps":["trace[1534552217] 'process raft request' (duration: 938.91205ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:33.354368Z","caller":"traceutil/trace.go:171","msg":"trace[1839945487] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"953.815186ms","start":"2026-08-22T10:33:32.400545Z","end":"2026-08-22T10:33:33.354361Z","steps":["trace[1839945487] 'process raft request' (duration: 953.117386ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.414949Z","time spent":"939.409637ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:33.354369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.410043Z","time spent":"944.259611ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:33.354403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.400528Z","time spent":"953.856128ms","remote":"127.0.0.1:60294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354456Z","caller":"traceutil/trace.go:171","msg":"trace[1026781432] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:517; }","duration":"846.837568ms","start":"2026-08-22T10:33:32.507596Z","end":"2026-08-22T10:33:33.354434Z","steps":["trace[1026781432] 'read index received' (duration: 835.744501ms)","trace[1026781432] 'applied index is now lower than readState.Index' (duration: 11.092107ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:33.354477Z","caller":"traceutil/trace.go:171","msg":"trace[671238536] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"953.501962ms","start":"2026-08-22T10:33:32.400967Z","end":"2026-08-22T10:33:33.354469Z","steps":["trace[671238536] 'process raft request' (duration: 952.729421ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:33.354485Z","caller":"traceutil/trace.go:171","msg":"trace[1405847585] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"939.726862ms","start":"2026-08-22T10:33:32.414748Z","end":"2026-08-22T10:33:33.354475Z","steps":["trace[1405847585] 'process raft request' (duration: 939.093343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.400931Z","time spent":"953.565423ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:33.354527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.414727Z","time spent":"939.778744ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354556Z","caller":"traceutil/trace.go:171","msg":"trace[1132608205] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"925.428445ms","start":"2026-08-22T10:33:32.429121Z","end":"2026-08-22T10:33:33.354549Z","steps":["trace[1132608205] 'process raft request' (duration: 924.973928ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:33.354533Z","caller":"traceutil/trace.go:171","msg":"trace[372012780] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"935.732861ms","start":"2026-08-22T10:33:32.418794Z","end":"2026-08-22T10:33:33.354527Z","steps":["trace[372012780] 'process raft request' (duration: 935.156452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.429108Z","time spent":"925.465846ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354625Z","caller":"traceutil/trace.go:171","msg":"trace[1229168592] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"899.279556ms","start":"2026-08-22T10:33:32.455337Z","end":"2026-08-22T10:33:33.354616Z","steps":["trace[1229168592] 'process raft request' (duration: 898.87714ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.418782Z","time spent":"935.814242ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:33.35466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.455322Z","time spent":"899.322277ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:33.354719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.120914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:33:33.354737Z","caller":"traceutil/trace.go:171","msg":"trace[894859163] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"809.190286ms","start":"2026-08-22T10:33:32.545534Z","end":"2026-08-22T10:33:33.354724Z","steps":["trace[894859163] 'process raft request' (duration: 808.79014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.545513Z","time spent":"809.248586ms","remote":"127.0.0.1:59780","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-22T10:33:33.354755Z","caller":"traceutil/trace.go:171","msg":"trace[89248045] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:511; }","duration":"847.198774ms","start":"2026-08-22T10:33:32.507548Z","end":"2026-08-22T10:33:33.354747Z","steps":["trace[89248045] 'agreement among raft nodes before linearized reading' (duration: 847.007791ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:33.354826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:32.507533Z","time spent":"847.286065ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:33:39.442907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.567362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vpt2h\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-22T10:33:39.443015Z","caller":"traceutil/trace.go:171","msg":"trace[1595594177] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vpt2h; range_end:; response_count:1; response_revision:602; }","duration":"185.692354ms","start":"2026-08-22T10:33:39.257288Z","end":"2026-08-22T10:33:39.44298Z","steps":["trace[1595594177] 'range keys from in-memory index tree' (duration: 185.379479ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:39.660995Z","caller":"traceutil/trace.go:171","msg":"trace[465294411] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"263.765444ms","start":"2026-08-22T10:33:39.397151Z","end":"2026-08-22T10:33:39.660916Z","steps":["trace[465294411] 'read index received' (duration: 257.234438ms)","trace[465294411] 'applied index is now lower than readState.Index' (duration: 6.529046ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:39.661021Z","caller":"traceutil/trace.go:171","msg":"trace[1378609948] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"399.613334ms","start":"2026-08-22T10:33:39.261363Z","end":"2026-08-22T10:33:39.660976Z","steps":["trace[1378609948] 'process raft request' (duration: 393.26163ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:39.661223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.261335Z","time spent":"399.797176ms","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:39.661275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.099358ms","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-08-22T10:33:39.66132Z","caller":"traceutil/trace.go:171","msg":"trace[1244128824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:603; }","duration":"264.211789ms","start":"2026-08-22T10:33:39.397096Z","end":"2026-08-22T10:33:39.661308Z","steps":["trace[1244128824] 'agreement among raft nodes before linearized reading' (duration: 264.012556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:40.308161Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922128133100147476,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:33:40.485193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.0238ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:33:40.48541Z","caller":"traceutil/trace.go:171","msg":"trace[860821089] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.028022543s","start":"2026-08-22T10:33:39.457339Z","end":"2026-08-22T10:33:40.485362Z","steps":["trace[860821089] 'process raft request' (duration: 853.412134ms)","trace[860821089] 'compare' (duration: 173.855168ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:40.485569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.457315Z","time spent":"1.028172415s","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:33:40.531913Z","caller":"traceutil/trace.go:171","msg":"trace[1577701437] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"865.328464ms","start":"2026-08-22T10:33:39.666552Z","end":"2026-08-22T10:33:40.53188Z","steps":["trace[1577701437] 'process raft request' (duration: 865.141712ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:40.53211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.666532Z","time spent":"865.460917ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:33:40.532192Z","caller":"traceutil/trace.go:171","msg":"trace[1267857830] linearizableReadLoop","detail":"{readStateIndex:633; appliedIndex:629; }","duration":"724.38898ms","start":"2026-08-22T10:33:39.807722Z","end":"2026-08-22T10:33:40.532111Z","steps":["trace[1267857830] 'read index received' (duration: 503.042795ms)","trace[1267857830] 'applied index is now lower than readState.Index' (duration: 221.342605ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:33:40.532438Z","caller":"traceutil/trace.go:171","msg":"trace[920557182] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"807.598724ms","start":"2026-08-22T10:33:39.724819Z","end":"2026-08-22T10:33:40.532418Z","steps":["trace[920557182] 'process raft request' (duration: 806.991495ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:40.532628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.724796Z","time spent":"807.737517ms","remote":"127.0.0.1:60276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-22T10:33:40.532685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"599.540632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-22T10:33:40.532819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.931995ms","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-08-22T10:33:40.532894Z","caller":"traceutil/trace.go:171","msg":"trace[893075044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:606; }","duration":"259.081627ms","start":"2026-08-22T10:33:40.273792Z","end":"2026-08-22T10:33:40.532873Z","steps":["trace[893075044] 'agreement among raft nodes before linearized reading' (duration: 258.791312ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:40.532882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.13491ms","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-08-22T10:33:40.532826Z","caller":"traceutil/trace.go:171","msg":"trace[1456946829] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:606; }","duration":"599.666944ms","start":"2026-08-22T10:33:39.933066Z","end":"2026-08-22T10:33:40.532733Z","steps":["trace[1456946829] 'agreement among raft nodes before linearized reading' (duration: 599.474671ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:40.532971Z","caller":"traceutil/trace.go:171","msg":"trace[1553553047] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:606; }","duration":"725.236382ms","start":"2026-08-22T10:33:39.807714Z","end":"2026-08-22T10:33:40.532951Z","steps":["trace[1553553047] 'agreement among raft nodes before linearized reading' (duration: 724.927177ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:40.532995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.933043Z","time spent":"599.938487ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:33:40.533028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:39.807665Z","time spent":"725.353764ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:33:42.392496Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.434316567s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:33:42.393988Z","caller":"traceutil/trace.go:171","msg":"trace[402163359] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"1.43724923s","start":"2026-08-22T10:33:40.956719Z","end":"2026-08-22T10:33:42.393968Z","steps":["trace[402163359] 'process raft request' (duration: 1.436683301s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:42.394149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:40.956688Z","time spent":"1.437361212s","remote":"127.0.0.1:59844","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:43.759971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.379413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:33:43.760101Z","caller":"traceutil/trace.go:171","msg":"trace[1831796785] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"252.564566ms","start":"2026-08-22T10:33:43.50751Z","end":"2026-08-22T10:33:43.760074Z","steps":["trace[1831796785] 'range keys from in-memory index tree' (duration: 252.165839ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:58.740023Z","caller":"traceutil/trace.go:171","msg":"trace[2050335704] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"232.824594ms","start":"2026-08-22T10:33:58.507176Z","end":"2026-08-22T10:33:58.74Z","steps":["trace[2050335704] 'read index received' (duration: 232.699562ms)","trace[2050335704] 'applied index is now lower than readState.Index' (duration: 123.942µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:58.740193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.990626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:33:58.740709Z","caller":"traceutil/trace.go:171","msg":"trace[1258827155] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:778; }","duration":"233.574034ms","start":"2026-08-22T10:33:58.507123Z","end":"2026-08-22T10:33:58.740697Z","steps":["trace[1258827155] 'agreement among raft nodes before linearized reading' (duration: 233.010646ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:58.740083Z","caller":"traceutil/trace.go:171","msg":"trace[1577018729] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"352.850359ms","start":"2026-08-22T10:33:58.387201Z","end":"2026-08-22T10:33:58.740051Z","steps":["trace[1577018729] 'process raft request' (duration: 352.639796ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:58.740884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:58.387177Z","time spent":"353.63202ms","remote":"127.0.0.1:60004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:58.740347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.594805ms","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-08-22T10:33:58.74099Z","caller":"traceutil/trace.go:171","msg":"trace[1337352914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:778; }","duration":"135.276094ms","start":"2026-08-22T10:33:58.605702Z","end":"2026-08-22T10:33:58.740978Z","steps":["trace[1337352914] 'agreement among raft nodes before linearized reading' (duration: 134.510783ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:59.501636Z","caller":"traceutil/trace.go:171","msg":"trace[315422500] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"757.233078ms","start":"2026-08-22T10:33:58.744383Z","end":"2026-08-22T10:33:59.501616Z","steps":["trace[315422500] 'process raft request' (duration: 757.022804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:59.501925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:58.744362Z","time spent":"757.337789ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:33:59.507369Z","caller":"traceutil/trace.go:171","msg":"trace[106830057] linearizableReadLoop","detail":"{readStateIndex:814; appliedIndex:810; }","duration":"372.832064ms","start":"2026-08-22T10:33:59.134515Z","end":"2026-08-22T10:33:59.507347Z","steps":["trace[106830057] 'read index received' (duration: 367.011745ms)","trace[106830057] 'applied index is now lower than readState.Index' (duration: 5.819429ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:33:59.507622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.060677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:33:59.507701Z","caller":"traceutil/trace.go:171","msg":"trace[1083305967] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:782; }","duration":"373.192229ms","start":"2026-08-22T10:33:59.134464Z","end":"2026-08-22T10:33:59.507656Z","steps":["trace[1083305967] 'agreement among raft nodes before linearized reading' (duration: 373.050887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:59.507722Z","caller":"traceutil/trace.go:171","msg":"trace[249901464] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"762.384077ms","start":"2026-08-22T10:33:58.745317Z","end":"2026-08-22T10:33:59.507701Z","steps":["trace[249901464] 'process raft request' (duration: 761.683147ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:59.507754Z","caller":"traceutil/trace.go:171","msg":"trace[1223999922] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"761.80561ms","start":"2026-08-22T10:33:58.745921Z","end":"2026-08-22T10:33:59.507727Z","steps":["trace[1223999922] 'process raft request' (duration: 761.260613ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:33:59.507826Z","caller":"traceutil/trace.go:171","msg":"trace[839006221] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"761.786439ms","start":"2026-08-22T10:33:58.74603Z","end":"2026-08-22T10:33:59.507816Z","steps":["trace[839006221] 'process raft request' (duration: 761.196541ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:33:59.507891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:58.745909Z","time spent":"761.911361ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:59.50797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:58.746022Z","time spent":"761.86513ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:33:59.507748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:59.134416Z","time spent":"373.316231ms","remote":"127.0.0.1:60018","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":31,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:33:59.507826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:33:58.7453Z","time spent":"762.475718ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:34:06.535881Z","caller":"traceutil/trace.go:171","msg":"trace[141584720] transaction","detail":"{read_only:false; response_revision:866; number_of_response:1; }","duration":"126.456528ms","start":"2026-08-22T10:34:06.409398Z","end":"2026-08-22T10:34:06.535854Z","steps":["trace[141584720] 'process raft request' (duration: 125.351323ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:16.949135Z","caller":"traceutil/trace.go:171","msg":"trace[1209342289] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"120.945755ms","start":"2026-08-22T10:34:16.828164Z","end":"2026-08-22T10:34:16.94911Z","steps":["trace[1209342289] 'process raft request' (duration: 120.679552ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:16.949254Z","caller":"traceutil/trace.go:171","msg":"trace[1842017537] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"117.783095ms","start":"2026-08-22T10:34:16.831444Z","end":"2026-08-22T10:34:16.949227Z","steps":["trace[1842017537] 'process raft request' (duration: 117.545822ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:16.949355Z","caller":"traceutil/trace.go:171","msg":"trace[143595239] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"111.229201ms","start":"2026-08-22T10:34:16.838076Z","end":"2026-08-22T10:34:16.949305Z","steps":["trace[143595239] 'process raft request' (duration: 110.988498ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:16.949423Z","caller":"traceutil/trace.go:171","msg":"trace[2043896661] transaction","detail":"{read_only:false; response_revision:977; number_of_response:1; }","duration":"111.233691ms","start":"2026-08-22T10:34:16.83816Z","end":"2026-08-22T10:34:16.949394Z","steps":["trace[2043896661] 'process raft request' (duration: 111.045818ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:16.949975Z","caller":"traceutil/trace.go:171","msg":"trace[1728487187] transaction","detail":"{read_only:false; response_revision:978; number_of_response:1; }","duration":"110.575022ms","start":"2026-08-22T10:34:16.839368Z","end":"2026-08-22T10:34:16.949943Z","steps":["trace[1728487187] 'process raft request' (duration: 109.897073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:17.26548Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.660461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:34:17.265704Z","caller":"traceutil/trace.go:171","msg":"trace[1023256447] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"136.330918ms","start":"2026-08-22T10:34:17.129334Z","end":"2026-08-22T10:34:17.265665Z","steps":["trace[1023256447] 'process raft request' (duration: 14.408264ms)","trace[1023256447] 'compare' (duration: 121.266956ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:34:17.265912Z","caller":"traceutil/trace.go:171","msg":"trace[839256448] transaction","detail":"{read_only:false; response_revision:1068; number_of_response:1; }","duration":"128.553889ms","start":"2026-08-22T10:34:17.13733Z","end":"2026-08-22T10:34:17.265884Z","steps":["trace[839256448] 'process raft request' (duration: 128.312556ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:17.266006Z","caller":"traceutil/trace.go:171","msg":"trace[1694543034] transaction","detail":"{read_only:false; response_revision:1069; number_of_response:1; }","duration":"128.489978ms","start":"2026-08-22T10:34:17.137483Z","end":"2026-08-22T10:34:17.265973Z","steps":["trace[1694543034] 'process raft request' (duration: 128.246085ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:17.26614Z","caller":"traceutil/trace.go:171","msg":"trace[1168702779] transaction","detail":"{read_only:false; response_revision:1067; number_of_response:1; }","duration":"134.043749ms","start":"2026-08-22T10:34:17.131997Z","end":"2026-08-22T10:34:17.266041Z","steps":["trace[1168702779] 'process raft request' (duration: 133.576853ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:17.266253Z","caller":"traceutil/trace.go:171","msg":"trace[637943715] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"104.811183ms","start":"2026-08-22T10:34:17.161426Z","end":"2026-08-22T10:34:17.266237Z","steps":["trace[637943715] 'process raft request' (duration: 104.353298ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:17.266659Z","caller":"traceutil/trace.go:171","msg":"trace[567506450] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"101.511341ms","start":"2026-08-22T10:34:17.164947Z","end":"2026-08-22T10:34:17.266458Z","steps":["trace[567506450] 'process raft request' (duration: 101.058035ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:28.552057Z","caller":"traceutil/trace.go:171","msg":"trace[2141409095] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"139.804111ms","start":"2026-08-22T10:34:28.412226Z","end":"2026-08-22T10:34:28.55203Z","steps":["trace[2141409095] 'process raft request' (duration: 139.625439ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:29.549656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.597568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:34:29.549741Z","caller":"traceutil/trace.go:171","msg":"trace[96439354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1179; }","duration":"388.73143ms","start":"2026-08-22T10:34:29.160991Z","end":"2026-08-22T10:34:29.549723Z","steps":["trace[96439354] 'range keys from in-memory index tree' (duration: 388.455576ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:29.549782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:29.160971Z","time spent":"388.80154ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:34:29.549936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.525407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:34:29.550036Z","caller":"traceutil/trace.go:171","msg":"trace[330019570] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1179; }","duration":"458.688448ms","start":"2026-08-22T10:34:29.091329Z","end":"2026-08-22T10:34:29.550017Z","steps":["trace[330019570] 'count revisions from in-memory index tree' (duration: 458.430485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:34:29.550092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:34:29.09131Z","time spent":"458.763039ms","remote":"127.0.0.1:59896","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:34:52.890696Z","caller":"traceutil/trace.go:171","msg":"trace[1161999917] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"213.260765ms","start":"2026-08-22T10:34:52.677409Z","end":"2026-08-22T10:34:52.89067Z","steps":["trace[1161999917] 'process raft request' (duration: 213.117893ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:34:55.008511Z","caller":"traceutil/trace.go:171","msg":"trace[1002629738] transaction","detail":"{read_only:false; response_revision:1449; number_of_response:1; }","duration":"110.19297ms","start":"2026-08-22T10:34:54.898293Z","end":"2026-08-22T10:34:55.008486Z","steps":["trace[1002629738] 'process raft request' (duration: 110.077828ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:06.55976Z","caller":"traceutil/trace.go:171","msg":"trace[1495414708] transaction","detail":"{read_only:false; response_revision:1684; number_of_response:1; }","duration":"153.956932ms","start":"2026-08-22T10:35:06.40578Z","end":"2026-08-22T10:35:06.559737Z","steps":["trace[1495414708] 'process raft request' (duration: 148.894874ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:11.671561Z","caller":"traceutil/trace.go:171","msg":"trace[1128809247] linearizableReadLoop","detail":"{readStateIndex:1827; appliedIndex:1826; }","duration":"166.403003ms","start":"2026-08-22T10:35:11.505142Z","end":"2026-08-22T10:35:11.671545Z","steps":["trace[1128809247] 'read index received' (duration: 166.257831ms)","trace[1128809247] 'applied index is now lower than readState.Index' (duration: 144.452µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:11.67168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.533834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:11.671857Z","caller":"traceutil/trace.go:171","msg":"trace[1600409826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1752; }","duration":"166.746376ms","start":"2026-08-22T10:35:11.505101Z","end":"2026-08-22T10:35:11.671848Z","steps":["trace[1600409826] 'agreement among raft nodes before linearized reading' (duration: 166.516153ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:11.671643Z","caller":"traceutil/trace.go:171","msg":"trace[432749120] transaction","detail":"{read_only:false; response_revision:1752; number_of_response:1; }","duration":"172.406131ms","start":"2026-08-22T10:35:11.499215Z","end":"2026-08-22T10:35:11.671621Z","steps":["trace[432749120] 'process raft request' (duration: 172.23113ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:11.860479Z","caller":"traceutil/trace.go:171","msg":"trace[2139990607] transaction","detail":"{read_only:false; response_revision:1753; number_of_response:1; }","duration":"177.48856ms","start":"2026-08-22T10:35:11.682961Z","end":"2026-08-22T10:35:11.86045Z","steps":["trace[2139990607] 'process raft request' (duration: 110.42638ms)","trace[2139990607] 'compare' (duration: 66.955899ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:11.907184Z","caller":"traceutil/trace.go:171","msg":"trace[1527813840] transaction","detail":"{read_only:false; response_revision:1754; number_of_response:1; }","duration":"154.768889ms","start":"2026-08-22T10:35:11.752398Z","end":"2026-08-22T10:35:11.907167Z","steps":["trace[1527813840] 'process raft request' (duration: 154.671778ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:12.5107Z","caller":"traceutil/trace.go:171","msg":"trace[1185608849] transaction","detail":"{read_only:false; response_revision:1757; number_of_response:1; }","duration":"270.222842ms","start":"2026-08-22T10:35:12.240448Z","end":"2026-08-22T10:35:12.510671Z","steps":["trace[1185608849] 'process raft request' (duration: 270.095881ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:12.60613Z","caller":"traceutil/trace.go:171","msg":"trace[147978828] linearizableReadLoop","detail":"{readStateIndex:1834; appliedIndex:1831; }","duration":"100.726187ms","start":"2026-08-22T10:35:12.50538Z","end":"2026-08-22T10:35:12.606106Z","steps":["trace[147978828] 'read index received' (duration: 5.235671ms)","trace[147978828] 'applied index is now lower than readState.Index' (duration: 95.489606ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:12.606194Z","caller":"traceutil/trace.go:171","msg":"trace[1362012846] transaction","detail":"{read_only:false; response_revision:1758; number_of_response:1; }","duration":"364.371852ms","start":"2026-08-22T10:35:12.241782Z","end":"2026-08-22T10:35:12.606154Z","steps":["trace[1362012846] 'process raft request' (duration: 324.491435ms)","trace[1362012846] 'compare' (duration: 39.678595ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:12.606254Z","caller":"traceutil/trace.go:171","msg":"trace[1767904807] transaction","detail":"{read_only:false; response_revision:1759; number_of_response:1; }","duration":"363.471012ms","start":"2026-08-22T10:35:12.242771Z","end":"2026-08-22T10:35:12.606242Z","steps":["trace[1767904807] 'process raft request' (duration: 363.29088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:12.606373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:12.24176Z","time spent":"364.501853ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:12.606428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:12.242748Z","time spent":"363.533613ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:12.606907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.518435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:12.607023Z","caller":"traceutil/trace.go:171","msg":"trace[757025931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1759; }","duration":"101.680707ms","start":"2026-08-22T10:35:12.505323Z","end":"2026-08-22T10:35:12.607004Z","steps":["trace[757025931] 'agreement among raft nodes before linearized reading' (duration: 101.266872ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:13.625209Z","caller":"traceutil/trace.go:171","msg":"trace[1749462205] linearizableReadLoop","detail":"{readStateIndex:1836; appliedIndex:1835; }","duration":"115.97849ms","start":"2026-08-22T10:35:13.509211Z","end":"2026-08-22T10:35:13.625189Z","steps":["trace[1749462205] 'read index received' (duration: 115.767837ms)","trace[1749462205] 'applied index is now lower than readState.Index' (duration: 209.783µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:13.625332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.103371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:13.625369Z","caller":"traceutil/trace.go:171","msg":"trace[1675526663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1761; }","duration":"116.152892ms","start":"2026-08-22T10:35:13.509205Z","end":"2026-08-22T10:35:13.625358Z","steps":["trace[1675526663] 'agreement among raft nodes before linearized reading' (duration: 116.064561ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:13.625539Z","caller":"traceutil/trace.go:171","msg":"trace[1072187781] transaction","detail":"{read_only:false; response_revision:1761; number_of_response:1; }","duration":"270.141668ms","start":"2026-08-22T10:35:13.355384Z","end":"2026-08-22T10:35:13.625526Z","steps":["trace[1072187781] 'process raft request' (duration: 269.651522ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:22.33508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922128133100150006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:35:22.836309Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922128133100150006,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-22T10:35:23.009411Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.229766663s","expected-duration":"1s"} {"level":"info","ts":"2026-08-22T10:35:23.010043Z","caller":"traceutil/trace.go:171","msg":"trace[1247751615] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"1.230471272s","start":"2026-08-22T10:35:21.779537Z","end":"2026-08-22T10:35:23.010008Z","steps":["trace[1247751615] 'process raft request' (duration: 1.230221669s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.010255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.779519Z","time spent":"1.230625204s","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10970,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.123017Z","caller":"traceutil/trace.go:171","msg":"trace[1994719335] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"1.208204715s","start":"2026-08-22T10:35:21.914731Z","end":"2026-08-22T10:35:23.122935Z","steps":["trace[1994719335] 'process raft request' (duration: 1.165251099s)","trace[1994719335] 'compare' (duration: 42.761424ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:23.123235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.914706Z","time spent":"1.208451428s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.123768Z","caller":"traceutil/trace.go:171","msg":"trace[517017344] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1949; }","duration":"1.28876613s","start":"2026-08-22T10:35:21.834977Z","end":"2026-08-22T10:35:23.123743Z","steps":["trace[517017344] 'read index received' (duration: 1.174722549s)","trace[517017344] 'applied index is now lower than readState.Index' (duration: 114.042671ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:23.12387Z","caller":"traceutil/trace.go:171","msg":"trace[1625283030] transaction","detail":"{read_only:false; response_revision:1875; number_of_response:1; }","duration":"1.177236824s","start":"2026-08-22T10:35:21.946605Z","end":"2026-08-22T10:35:23.123842Z","steps":["trace[1625283030] 'process raft request' (duration: 1.176290483s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.124044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.946589Z","time spent":"1.177372875s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.12388Z","caller":"traceutil/trace.go:171","msg":"trace[1066269044] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"995.923357ms","start":"2026-08-22T10:35:22.127929Z","end":"2026-08-22T10:35:23.123852Z","steps":["trace[1066269044] 'process raft request' (duration: 995.681874ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.124207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.127896Z","time spent":"996.284411ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:23.124383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.289184294s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:35:23.124485Z","caller":"traceutil/trace.go:171","msg":"trace[602888558] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1876; }","duration":"1.28955533s","start":"2026-08-22T10:35:21.834916Z","end":"2026-08-22T10:35:23.124472Z","steps":["trace[602888558] 'agreement among raft nodes before linearized reading' (duration: 1.288949832s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.124669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.834895Z","time spent":"1.289757352s","remote":"127.0.0.1:60188","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":31,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:35:23.385254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.513201ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:35:23.385483Z","caller":"traceutil/trace.go:171","msg":"trace[1137741464] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"367.931607ms","start":"2026-08-22T10:35:23.017536Z","end":"2026-08-22T10:35:23.385468Z","steps":["trace[1137741464] 'process raft request' (duration: 367.818886ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.385604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.017521Z","time spent":"368.044828ms","remote":"127.0.0.1:60036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.385484Z","caller":"traceutil/trace.go:171","msg":"trace[1296045246] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"368.030278ms","start":"2026-08-22T10:35:23.017435Z","end":"2026-08-22T10:35:23.385465Z","steps":["trace[1296045246] 'process raft request' (duration: 250.150023ms)","trace[1296045246] 'compare' (duration: 117.347939ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:23.385768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.01741Z","time spent":"368.321421ms","remote":"127.0.0.1:60036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.385657Z","caller":"traceutil/trace.go:171","msg":"trace[1787090259] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"368.023768ms","start":"2026-08-22T10:35:23.017603Z","end":"2026-08-22T10:35:23.385627Z","steps":["trace[1787090259] 'process raft request' (duration: 367.813865ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.385952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.017583Z","time spent":"368.29123ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.385991Z","caller":"traceutil/trace.go:171","msg":"trace[1838397683] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"368.003226ms","start":"2026-08-22T10:35:23.017968Z","end":"2026-08-22T10:35:23.385971Z","steps":["trace[1838397683] 'process raft request' (duration: 367.570222ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.017954Z","time spent":"368.070567ms","remote":"127.0.0.1:59932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":978,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:23.386073Z","caller":"traceutil/trace.go:171","msg":"trace[1213722781] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"153.810802ms","start":"2026-08-22T10:35:23.232215Z","end":"2026-08-22T10:35:23.386026Z","steps":["trace[1213722781] 'process raft request' (duration: 153.703131ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:23.386038Z","caller":"traceutil/trace.go:171","msg":"trace[1174178069] linearizableReadLoop","detail":"{readStateIndex:1957; appliedIndex:1953; }","duration":"262.176609ms","start":"2026-08-22T10:35:23.123838Z","end":"2026-08-22T10:35:23.386014Z","steps":["trace[1174178069] 'read index received' (duration: 143.764148ms)","trace[1174178069] 'applied index is now lower than readState.Index' (duration: 118.409411ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:23.386255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.149734ms","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-08-22T10:35:23.386271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.417998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:35:23.386292Z","caller":"traceutil/trace.go:171","msg":"trace[1137674579] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1881; }","duration":"822.222865ms","start":"2026-08-22T10:35:22.56406Z","end":"2026-08-22T10:35:23.386283Z","steps":["trace[1137674579] 'agreement among raft nodes before linearized reading' (duration: 822.092133ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:23.386311Z","caller":"traceutil/trace.go:171","msg":"trace[1085600071] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"890.501679ms","start":"2026-08-22T10:35:22.495796Z","end":"2026-08-22T10:35:23.386297Z","steps":["trace[1085600071] 'agreement among raft nodes before linearized reading' (duration: 890.371488ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.564043Z","time spent":"822.275046ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.495776Z","time spent":"890.56445ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:35:23.38645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.041139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T10:35:23.386486Z","caller":"traceutil/trace.go:171","msg":"trace[126651573] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a; range_end:; response_count:1; response_revision:1881; }","duration":"369.10928ms","start":"2026-08-22T10:35:23.017366Z","end":"2026-08-22T10:35:23.386476Z","steps":["trace[126651573] 'agreement among raft nodes before linearized reading' (duration: 369.024589ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.38652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.017348Z","time spent":"369.165ms","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.134681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:35:23.386639Z","caller":"traceutil/trace.go:171","msg":"trace[919086369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1881; }","duration":"610.209433ms","start":"2026-08-22T10:35:22.776418Z","end":"2026-08-22T10:35:23.386628Z","steps":["trace[919086369] 'agreement among raft nodes before linearized reading' (duration: 610.126702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"879.356814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:35:23.386675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.776402Z","time spent":"610.266593ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:35:23.386702Z","caller":"traceutil/trace.go:171","msg":"trace[1874177930] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"879.531535ms","start":"2026-08-22T10:35:22.507155Z","end":"2026-08-22T10:35:23.386686Z","steps":["trace[1874177930] 'agreement among raft nodes before linearized reading' (duration: 879.135781ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.073912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:23.386778Z","caller":"traceutil/trace.go:171","msg":"trace[1223659203] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1881; }","duration":"769.211224ms","start":"2026-08-22T10:35:22.617556Z","end":"2026-08-22T10:35:23.386767Z","steps":["trace[1223659203] 'agreement among raft nodes before linearized reading' (duration: 769.093643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.464050241s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a\" ","response":"range_response_count:1 size:3627"} {"level":"warn","ts":"2026-08-22T10:35:23.38652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"648.912001ms","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-08-22T10:35:23.386831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.456356305s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:23.386866Z","caller":"traceutil/trace.go:171","msg":"trace[1986724636] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:1881; }","duration":"1.456416366s","start":"2026-08-22T10:35:21.930438Z","end":"2026-08-22T10:35:23.386855Z","steps":["trace[1986724636] 'agreement among raft nodes before linearized reading' (duration: 1.456367936s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:23.386881Z","caller":"traceutil/trace.go:171","msg":"trace[197712555] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1881; }","duration":"649.305085ms","start":"2026-08-22T10:35:22.737568Z","end":"2026-08-22T10:35:23.386873Z","steps":["trace[197712555] 'agreement among raft nodes before linearized reading' (duration: 648.84123ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:23.386883Z","caller":"traceutil/trace.go:171","msg":"trace[1151085463] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a; range_end:; response_count:1; response_revision:1881; }","duration":"1.464170173s","start":"2026-08-22T10:35:21.9227Z","end":"2026-08-22T10:35:23.38687Z","steps":["trace[1151085463] 'agreement among raft nodes before linearized reading' (duration: 1.463919651s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.386908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.73755Z","time spent":"649.352346ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.930426Z","time spent":"1.456472737s","remote":"127.0.0.1:49706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":29,"request content":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:35:23.386908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.507136Z","time spent":"879.760548ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:21.922681Z","time spent":"1.464230564s","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3651,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-70b76ca1-3b7c-4bb8-bb56-f1c095f4c96a\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.236259849s","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-08-22T10:35:23.387018Z","caller":"traceutil/trace.go:171","msg":"trace[2091770780] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1881; }","duration":"1.236533612s","start":"2026-08-22T10:35:22.150476Z","end":"2026-08-22T10:35:23.387009Z","steps":["trace[2091770780] 'agreement among raft nodes before linearized reading' (duration: 1.235973956s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.38705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.150459Z","time spent":"1.236583042s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:35:23.386818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:22.61753Z","time spent":"769.276705ms","remote":"127.0.0.1:43000","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:35:23.919756Z","caller":"traceutil/trace.go:171","msg":"trace[2046562168] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"527.18985ms","start":"2026-08-22T10:35:23.392542Z","end":"2026-08-22T10:35:23.919732Z","steps":["trace[2046562168] 'process raft request' (duration: 527.047838ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:23.920099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.392522Z","time spent":"527.302261ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:24.024258Z","caller":"traceutil/trace.go:171","msg":"trace[875492178] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"630.944463ms","start":"2026-08-22T10:35:23.393293Z","end":"2026-08-22T10:35:24.024237Z","steps":["trace[875492178] 'process raft request' (duration: 630.862273ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:24.024281Z","caller":"traceutil/trace.go:171","msg":"trace[386748158] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"631.047786ms","start":"2026-08-22T10:35:23.393214Z","end":"2026-08-22T10:35:24.024262Z","steps":["trace[386748158] 'process raft request' (duration: 630.048404ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:24.024377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.393206Z","time spent":"631.122766ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:24.024427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.393263Z","time spent":"631.060695ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:24.024514Z","caller":"traceutil/trace.go:171","msg":"trace[898869814] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"631.146406ms","start":"2026-08-22T10:35:23.393352Z","end":"2026-08-22T10:35:24.024498Z","steps":["trace[898869814] 'process raft request' (duration: 630.846453ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:24.024736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.393333Z","time spent":"631.243737ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:24.024792Z","caller":"traceutil/trace.go:171","msg":"trace[1777162594] transaction","detail":"{read_only:false; response_revision:1886; number_of_response:1; }","duration":"630.63147ms","start":"2026-08-22T10:35:23.394146Z","end":"2026-08-22T10:35:24.024778Z","steps":["trace[1777162594] 'process raft request' (duration: 630.281627ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:24.024811Z","caller":"traceutil/trace.go:171","msg":"trace[1408353118] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"629.632049ms","start":"2026-08-22T10:35:23.395151Z","end":"2026-08-22T10:35:24.024783Z","steps":["trace[1408353118] 'process raft request' (duration: 629.503668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:24.02487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.394126Z","time spent":"630.709341ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:24.024978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:23.395144Z","time spent":"629.750221ms","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:29.520713Z","caller":"traceutil/trace.go:171","msg":"trace[1584848844] transaction","detail":"{read_only:false; response_revision:1946; number_of_response:1; }","duration":"114.170654ms","start":"2026-08-22T10:35:29.406528Z","end":"2026-08-22T10:35:29.520698Z","steps":["trace[1584848844] 'process raft request' (duration: 114.060823ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:34.816512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.096044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-857cr\" ","response":"range_response_count:1 size:5855"} {"level":"warn","ts":"2026-08-22T10:35:34.816571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.521889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:35:34.816599Z","caller":"traceutil/trace.go:171","msg":"trace[981465728] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-cluster-operator-54f767cff8-857cr; range_end:; response_count:1; response_revision:1976; }","duration":"229.209896ms","start":"2026-08-22T10:35:34.587366Z","end":"2026-08-22T10:35:34.816576Z","steps":["trace[981465728] 'range keys from in-memory index tree' (duration: 228.952802ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:34.816648Z","caller":"traceutil/trace.go:171","msg":"trace[595031201] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1976; }","duration":"238.651501ms","start":"2026-08-22T10:35:34.577977Z","end":"2026-08-22T10:35:34.816628Z","steps":["trace[595031201] 'range keys from in-memory index tree' (duration: 238.241927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.169844Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5922128133100150245,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-22T10:35:36.432143Z","caller":"traceutil/trace.go:171","msg":"trace[906731482] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"856.703475ms","start":"2026-08-22T10:35:35.575415Z","end":"2026-08-22T10:35:36.432119Z","steps":["trace[906731482] 'process raft request' (duration: 856.575204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.432312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:35.575388Z","time spent":"856.822416ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:36.433416Z","caller":"traceutil/trace.go:171","msg":"trace[1349114470] linearizableReadLoop","detail":"{readStateIndex:2073; appliedIndex:2071; }","duration":"763.906717ms","start":"2026-08-22T10:35:35.669413Z","end":"2026-08-22T10:35:36.43332Z","steps":["trace[1349114470] 'read index received' (duration: 762.671764ms)","trace[1349114470] 'applied index is now lower than readState.Index' (duration: 1.234213ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:36.433691Z","caller":"traceutil/trace.go:171","msg":"trace[2121736530] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"188.065272ms","start":"2026-08-22T10:35:36.24561Z","end":"2026-08-22T10:35:36.433675Z","steps":["trace[2121736530] 'process raft request' (duration: 187.669677ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:36.433638Z","caller":"traceutil/trace.go:171","msg":"trace[1750342069] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"288.693356ms","start":"2026-08-22T10:35:36.144923Z","end":"2026-08-22T10:35:36.433616Z","steps":["trace[1750342069] 'process raft request' (duration: 288.23911ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.433842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"764.405503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11985"} {"level":"info","ts":"2026-08-22T10:35:36.433923Z","caller":"traceutil/trace.go:171","msg":"trace[506558320] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:1993; }","duration":"764.524364ms","start":"2026-08-22T10:35:35.669381Z","end":"2026-08-22T10:35:36.433906Z","steps":["trace[506558320] 'agreement among raft nodes before linearized reading' (duration: 764.289472ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.433964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:35.669364Z","time spent":"764.591915ms","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12009,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T10:35:36.433973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.553372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-08-22T10:35:36.434139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.247578ms","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-08-22T10:35:36.434178Z","caller":"traceutil/trace.go:171","msg":"trace[487799218] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1993; }","duration":"345.296218ms","start":"2026-08-22T10:35:36.088871Z","end":"2026-08-22T10:35:36.434167Z","steps":["trace[487799218] 'agreement among raft nodes before linearized reading' (duration: 345.204597ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.43421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:36.088864Z","time spent":"345.338619ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:36.434187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.429747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:36.434296Z","caller":"traceutil/trace.go:171","msg":"trace[1691846775] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1993; }","duration":"164.584909ms","start":"2026-08-22T10:35:36.269692Z","end":"2026-08-22T10:35:36.434277Z","steps":["trace[1691846775] 'agreement among raft nodes before linearized reading' (duration: 164.403637ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:36.434303Z","caller":"traceutil/trace.go:171","msg":"trace[2019235867] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1993; }","duration":"610.642014ms","start":"2026-08-22T10:35:35.823363Z","end":"2026-08-22T10:35:36.434005Z","steps":["trace[2019235867] 'agreement among raft nodes before linearized reading' (duration: 610.502682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.434407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.052527ms","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-08-22T10:35:36.434438Z","caller":"traceutil/trace.go:171","msg":"trace[910422363] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1993; }","duration":"347.100437ms","start":"2026-08-22T10:35:36.087329Z","end":"2026-08-22T10:35:36.43443Z","steps":["trace[910422363] 'agreement among raft nodes before linearized reading' (duration: 346.687653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.434462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:36.087315Z","time spent":"347.142268ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:36.434687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.874645ms","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-08-22T10:35:36.434719Z","caller":"traceutil/trace.go:171","msg":"trace[2006699604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1993; }","duration":"346.927846ms","start":"2026-08-22T10:35:36.087783Z","end":"2026-08-22T10:35:36.434711Z","steps":["trace[2006699604] 'agreement among raft nodes before linearized reading' (duration: 346.34671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.434745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:36.087771Z","time spent":"346.968197ms","remote":"127.0.0.1:60026","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-08-22T10:35:36.434781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.671529ms","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-08-22T10:35:36.434836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.471026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-08-22T10:35:36.43486Z","caller":"traceutil/trace.go:171","msg":"trace[34218404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1993; }","duration":"352.010702ms","start":"2026-08-22T10:35:36.082809Z","end":"2026-08-22T10:35:36.434819Z","steps":["trace[34218404] 'agreement among raft nodes before linearized reading' (duration: 351.372515ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:36.434885Z","caller":"traceutil/trace.go:171","msg":"trace[1583666594] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:1993; }","duration":"547.546238ms","start":"2026-08-22T10:35:35.887327Z","end":"2026-08-22T10:35:36.434873Z","steps":["trace[1583666594] 'agreement among raft nodes before linearized reading' (duration: 546.870341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.434591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:35.823315Z","time spent":"611.018947ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:35:36.434918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:35.887309Z","time spent":"547.602658ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-22T10:35:36.434976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:36.082777Z","time spent":"352.111424ms","remote":"127.0.0.1:60026","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-08-22T10:35:36.434413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.374042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:35:36.43514Z","caller":"traceutil/trace.go:171","msg":"trace[36787942] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1993; }","duration":"451.14692ms","start":"2026-08-22T10:35:35.983977Z","end":"2026-08-22T10:35:36.435124Z","steps":["trace[36787942] 'agreement among raft nodes before linearized reading' (duration: 450.158039ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:36.435244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:35.983954Z","time spent":"451.273752ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T10:35:49.477443Z","caller":"traceutil/trace.go:171","msg":"trace[850860750] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"170.852737ms","start":"2026-08-22T10:35:49.306555Z","end":"2026-08-22T10:35:49.477408Z","steps":["trace[850860750] 'process raft request' (duration: 120.343819ms)","trace[850860750] 'compare' (duration: 50.083984ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:49.477581Z","caller":"traceutil/trace.go:171","msg":"trace[591481944] transaction","detail":"{read_only:false; response_revision:2074; number_of_response:1; }","duration":"168.433051ms","start":"2026-08-22T10:35:49.309117Z","end":"2026-08-22T10:35:49.47755Z","steps":["trace[591481944] 'process raft request' (duration: 168.160408ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:49.847784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.590116ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T10:35:57.028884Z","caller":"traceutil/trace.go:171","msg":"trace[59538978] linearizableReadLoop","detail":"{readStateIndex:2194; appliedIndex:2193; }","duration":"336.896137ms","start":"2026-08-22T10:35:56.691967Z","end":"2026-08-22T10:35:57.028863Z","steps":["trace[59538978] 'read index received' (duration: 336.804054ms)","trace[59538978] 'applied index is now lower than readState.Index' (duration: 90.993µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:57.028979Z","caller":"traceutil/trace.go:171","msg":"trace[1843112870] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"365.275613ms","start":"2026-08-22T10:35:56.66366Z","end":"2026-08-22T10:35:57.028936Z","steps":["trace[1843112870] 'process raft request' (duration: 365.041414ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.029148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.66362Z","time spent":"365.4527ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:35:57.029317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.330485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:35:57.029461Z","caller":"traceutil/trace.go:171","msg":"trace[381443427] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"337.513662ms","start":"2026-08-22T10:35:56.691926Z","end":"2026-08-22T10:35:57.02944Z","steps":["trace[381443427] 'agreement among raft nodes before linearized reading' (duration: 337.130458ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.029502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.691914Z","time spent":"337.578464ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:35:57.196934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.09677ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:35:57.197098Z","caller":"traceutil/trace.go:171","msg":"trace[748989658] linearizableReadLoop","detail":"{readStateIndex:2195; appliedIndex:2194; }","duration":"168.049842ms","start":"2026-08-22T10:35:57.029031Z","end":"2026-08-22T10:35:57.197081Z","steps":["trace[748989658] 'read index received' (duration: 63.567668ms)","trace[748989658] 'applied index is now lower than readState.Index' (duration: 104.480834ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:57.197335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.218081ms","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-08-22T10:35:57.19741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.797406ms","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-08-22T10:35:57.197463Z","caller":"traceutil/trace.go:171","msg":"trace[1992623760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2110; }","duration":"368.861338ms","start":"2026-08-22T10:35:56.828588Z","end":"2026-08-22T10:35:57.19745Z","steps":["trace[1992623760] 'agreement among raft nodes before linearized reading' (duration: 368.580118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.197514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.828585Z","time spent":"368.91995ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-22T10:35:57.197522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.286625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:35:57.197564Z","caller":"traceutil/trace.go:171","msg":"trace[137852358] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2110; }","duration":"369.337056ms","start":"2026-08-22T10:35:56.828215Z","end":"2026-08-22T10:35:57.197552Z","steps":["trace[137852358] 'agreement among raft nodes before linearized reading' (duration: 369.228352ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.197616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.82821Z","time spent":"369.394378ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-22T10:35:57.197419Z","caller":"traceutil/trace.go:171","msg":"trace[1497408675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2110; }","duration":"370.329024ms","start":"2026-08-22T10:35:56.827072Z","end":"2026-08-22T10:35:57.197401Z","steps":["trace[1497408675] 'agreement among raft nodes before linearized reading' (duration: 370.113956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.197852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.827067Z","time spent":"370.763502ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:57.197876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.422009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:35:57.197921Z","caller":"traceutil/trace.go:171","msg":"trace[81525497] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2110; }","duration":"244.518472ms","start":"2026-08-22T10:35:56.953389Z","end":"2026-08-22T10:35:57.197907Z","steps":["trace[81525497] 'agreement among raft nodes before linearized reading' (duration: 244.366407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.197458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.604141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:35:57.198056Z","caller":"traceutil/trace.go:171","msg":"trace[564876146] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2110; }","duration":"373.199883ms","start":"2026-08-22T10:35:56.824838Z","end":"2026-08-22T10:35:57.198038Z","steps":["trace[564876146] 'agreement among raft nodes before linearized reading' (duration: 372.292699ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.198121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.824817Z","time spent":"373.286597ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:57.198057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.583663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:35:57.198275Z","caller":"traceutil/trace.go:171","msg":"trace[1995684632] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2110; }","duration":"371.851332ms","start":"2026-08-22T10:35:56.82641Z","end":"2026-08-22T10:35:57.198261Z","steps":["trace[1995684632] 'agreement among raft nodes before linearized reading' (duration: 371.076863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.198516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:56.826388Z","time spent":"372.109452ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:35:57.198205Z","caller":"traceutil/trace.go:171","msg":"trace[781736665] transaction","detail":"{read_only:false; response_revision:2110; number_of_response:1; }","duration":"214.739034ms","start":"2026-08-22T10:35:56.983273Z","end":"2026-08-22T10:35:57.198012Z","steps":["trace[781736665] 'process raft request' (duration: 109.48595ms)","trace[781736665] 'compare' (duration: 103.946774ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:57.45869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.29777ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:35:57.458774Z","caller":"traceutil/trace.go:171","msg":"trace[1248631614] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"256.053983ms","start":"2026-08-22T10:35:57.202705Z","end":"2026-08-22T10:35:57.458759Z","steps":["trace[1248631614] 'process raft request' (duration: 116.620748ms)","trace[1248631614] 'compare' (duration: 139.065491ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:57.481969Z","caller":"traceutil/trace.go:171","msg":"trace[1378626301] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"278.423672ms","start":"2026-08-22T10:35:57.203512Z","end":"2026-08-22T10:35:57.481936Z","steps":["trace[1378626301] 'process raft request' (duration: 278.36992ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:57.48243Z","caller":"traceutil/trace.go:171","msg":"trace[568886289] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"279.288655ms","start":"2026-08-22T10:35:57.203127Z","end":"2026-08-22T10:35:57.482416Z","steps":["trace[568886289] 'process raft request' (duration: 278.495155ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:57.48262Z","caller":"traceutil/trace.go:171","msg":"trace[1199331427] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"279.416019ms","start":"2026-08-22T10:35:57.203191Z","end":"2026-08-22T10:35:57.482607Z","steps":["trace[1199331427] 'process raft request' (duration: 278.596919ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:57.482742Z","caller":"traceutil/trace.go:171","msg":"trace[1396269532] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"279.459201ms","start":"2026-08-22T10:35:57.203228Z","end":"2026-08-22T10:35:57.482687Z","steps":["trace[1396269532] 'process raft request' (duration: 278.604219ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:57.506224Z","caller":"traceutil/trace.go:171","msg":"trace[529397761] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"130.463284ms","start":"2026-08-22T10:35:57.37573Z","end":"2026-08-22T10:35:57.506193Z","steps":["trace[529397761] 'process raft request' (duration: 130.321159ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.976237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.513832ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:35:57.976497Z","caller":"traceutil/trace.go:171","msg":"trace[1239499563] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2202; }","duration":"433.812285ms","start":"2026-08-22T10:35:57.542668Z","end":"2026-08-22T10:35:57.97648Z","steps":["trace[1239499563] 'read index received' (duration: 218.981331ms)","trace[1239499563] 'applied index is now lower than readState.Index' (duration: 214.829223ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:57.976564Z","caller":"traceutil/trace.go:171","msg":"trace[1858339758] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"468.231454ms","start":"2026-08-22T10:35:57.508281Z","end":"2026-08-22T10:35:57.976513Z","steps":["trace[1858339758] 'process raft request' (duration: 253.345648ms)","trace[1858339758] 'compare' (duration: 214.357826ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:35:57.976642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.96282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:35:57.976661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:57.508266Z","time spent":"468.342958ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:57.976681Z","caller":"traceutil/trace.go:171","msg":"trace[1352547695] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2118; }","duration":"434.036144ms","start":"2026-08-22T10:35:57.542632Z","end":"2026-08-22T10:35:57.976669Z","steps":["trace[1352547695] 'agreement among raft nodes before linearized reading' (duration: 433.899489ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:57.977094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:57.54262Z","time spent":"434.403627ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:35:59.414906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.18254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T10:35:59.415005Z","caller":"traceutil/trace.go:171","msg":"trace[839506006] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:2120; }","duration":"127.345536ms","start":"2026-08-22T10:35:59.28764Z","end":"2026-08-22T10:35:59.414986Z","steps":["trace[839506006] 'count revisions from in-memory index tree' (duration: 127.082026ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:59.414955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.328512ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:35:59.415065Z","caller":"traceutil/trace.go:171","msg":"trace[1204764373] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2120; }","duration":"253.55021ms","start":"2026-08-22T10:35:59.161496Z","end":"2026-08-22T10:35:59.415046Z","steps":["trace[1204764373] 'range keys from in-memory index tree' (duration: 253.255629ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:35:59.816732Z","caller":"traceutil/trace.go:171","msg":"trace[994973860] linearizableReadLoop","detail":"{readStateIndex:2206; appliedIndex:2205; }","duration":"353.797777ms","start":"2026-08-22T10:35:59.462911Z","end":"2026-08-22T10:35:59.816709Z","steps":["trace[994973860] 'read index received' (duration: 353.642131ms)","trace[994973860] 'applied index is now lower than readState.Index' (duration: 154.526µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:35:59.816812Z","caller":"traceutil/trace.go:171","msg":"trace[222146486] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"411.223119ms","start":"2026-08-22T10:35:59.405558Z","end":"2026-08-22T10:35:59.816781Z","steps":["trace[222146486] 'process raft request' (duration: 410.9754ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:59.817029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.100588ms","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-08-22T10:35:59.817116Z","caller":"traceutil/trace.go:171","msg":"trace[204040424] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2121; }","duration":"354.244834ms","start":"2026-08-22T10:35:59.462859Z","end":"2026-08-22T10:35:59.817104Z","steps":["trace[204040424] 'agreement among raft nodes before linearized reading' (duration: 354.015915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:59.817168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.462841Z","time spent":"354.312335ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:35:59.817662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.405534Z","time spent":"411.548272ms","remote":"127.0.0.1:59934","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:59.977311Z","caller":"traceutil/trace.go:171","msg":"trace[160269059] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"465.509114ms","start":"2026-08-22T10:35:59.511781Z","end":"2026-08-22T10:35:59.97729Z","steps":["trace[160269059] 'process raft request' (duration: 461.135169ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:59.977559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.511755Z","time spent":"465.697821ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:35:59.977913Z","caller":"traceutil/trace.go:171","msg":"trace[2040758967] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"432.007662ms","start":"2026-08-22T10:35:59.545856Z","end":"2026-08-22T10:35:59.977864Z","steps":["trace[2040758967] 'process raft request' (duration: 430.894231ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:35:59.978019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.545837Z","time spent":"432.110975ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:00.141353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.50245ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T10:36:00.141839Z","caller":"traceutil/trace.go:171","msg":"trace[1866919827] linearizableReadLoop","detail":"{readStateIndex:2210; appliedIndex:2206; }","duration":"324.862631ms","start":"2026-08-22T10:35:59.81696Z","end":"2026-08-22T10:36:00.141822Z","steps":["trace[1866919827] 'read index received' (duration: 155.970284ms)","trace[1866919827] 'applied index is now lower than readState.Index' (duration: 168.891287ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:00.14188Z","caller":"traceutil/trace.go:171","msg":"trace[109373033] transaction","detail":"{read_only:false; response_revision:2124; number_of_response:1; }","duration":"413.675196ms","start":"2026-08-22T10:35:59.728188Z","end":"2026-08-22T10:36:00.141863Z","steps":["trace[109373033] 'process raft request' (duration: 413.223199ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:00.142158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.728167Z","time spent":"413.76219ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:00.489458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002836502s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-08-22T10:36:00.489545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002847612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-22T10:36:00.489555Z","caller":"traceutil/trace.go:171","msg":"trace[1199942158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2124; }","duration":"1.002981057s","start":"2026-08-22T10:35:59.486549Z","end":"2026-08-22T10:36:00.48953Z","steps":["trace[1199942158] 'agreement among raft nodes before linearized reading' (duration: 655.326378ms)","trace[1199942158] 'range keys from in-memory index tree' (duration: 347.449711ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:00.489591Z","caller":"traceutil/trace.go:171","msg":"trace[1945749910] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2124; }","duration":"1.002918705s","start":"2026-08-22T10:35:59.486657Z","end":"2026-08-22T10:36:00.489576Z","steps":["trace[1945749910] 'agreement among raft nodes before linearized reading' (duration: 655.211533ms)","trace[1945749910] 'range keys from in-memory index tree' (duration: 347.439731ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:00.489615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.486528Z","time spent":"1.00307132s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:36:00.489624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.486642Z","time spent":"1.002974957s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:36:00.489645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002470228s","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-08-22T10:36:00.48969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.002885964s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-22T10:36:00.489877Z","caller":"traceutil/trace.go:171","msg":"trace[1077728971] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2124; }","duration":"1.002725188s","start":"2026-08-22T10:35:59.48713Z","end":"2026-08-22T10:36:00.489855Z","steps":["trace[1077728971] 'agreement among raft nodes before linearized reading' (duration: 654.730046ms)","trace[1077728971] 'range keys from in-memory index tree' (duration: 347.639378ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:00.489945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.670862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:36:00.489959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.487122Z","time spent":"1.002824161s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-22T10:36:00.489991Z","caller":"traceutil/trace.go:171","msg":"trace[1553284800] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2124; }","duration":"982.733724ms","start":"2026-08-22T10:35:59.507241Z","end":"2026-08-22T10:36:00.489974Z","steps":["trace[1553284800] 'agreement among raft nodes before linearized reading' (duration: 634.598887ms)","trace[1553284800] 'range keys from in-memory index tree' (duration: 347.619838ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:00.490038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.507226Z","time spent":"982.803737ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:36:00.489706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.797035ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:36:00.489924Z","caller":"traceutil/trace.go:171","msg":"trace[247989569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2124; }","duration":"1.003148123s","start":"2026-08-22T10:35:59.486747Z","end":"2026-08-22T10:36:00.489895Z","steps":["trace[247989569] 'agreement among raft nodes before linearized reading' (duration: 655.095409ms)","trace[247989569] 'range keys from in-memory index tree' (duration: 347.727062ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:00.490247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.486731Z","time spent":"1.003494447s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-22T10:36:00.490513Z","caller":"traceutil/trace.go:171","msg":"trace[94959671] linearizableReadLoop","detail":"{readStateIndex:2212; appliedIndex:2210; }","duration":"348.611615ms","start":"2026-08-22T10:36:00.141882Z","end":"2026-08-22T10:36:00.490493Z","steps":["trace[94959671] 'read index received' (duration: 225.468µs)","trace[94959671] 'applied index is now lower than readState.Index' (duration: 348.385087ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:36:00.49073Z","caller":"traceutil/trace.go:171","msg":"trace[2138871489] transaction","detail":"{read_only:false; response_revision:2125; number_of_response:1; }","duration":"668.638873ms","start":"2026-08-22T10:35:59.822075Z","end":"2026-08-22T10:36:00.490713Z","steps":["trace[2138871489] 'process raft request' (duration: 319.739727ms)","trace[2138871489] 'compare' (duration: 347.717112ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:00.49082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.822049Z","time spent":"668.723296ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:00.490752Z","caller":"traceutil/trace.go:171","msg":"trace[781068821] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"508.2026ms","start":"2026-08-22T10:35:59.982531Z","end":"2026-08-22T10:36:00.490734Z","steps":["trace[781068821] 'process raft request' (duration: 507.768214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:00.491001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.014546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:36:00.491039Z","caller":"traceutil/trace.go:171","msg":"trace[900782100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2126; }","duration":"418.088728ms","start":"2026-08-22T10:36:00.072939Z","end":"2026-08-22T10:36:00.491028Z","steps":["trace[900782100] 'agreement among raft nodes before linearized reading' (duration: 417.987615ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:00.490935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.960894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18ce1a3c6e7608a4\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-08-22T10:36:00.491115Z","caller":"traceutil/trace.go:171","msg":"trace[1267726475] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18ce1a3c6e7608a4; range_end:; response_count:1; response_revision:2126; }","duration":"291.136911ms","start":"2026-08-22T10:36:00.199956Z","end":"2026-08-22T10:36:00.491093Z","steps":["trace[1267726475] 'agreement among raft nodes before linearized reading' (duration: 290.863561ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:00.49125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:35:59.98249Z","time spent":"508.497601ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:00.491069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.072918Z","time spent":"418.141941ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:36:02.025312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.226761322s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:42"} {"level":"info","ts":"2026-08-22T10:36:02.02582Z","caller":"traceutil/trace.go:171","msg":"trace[1928256198] transaction","detail":"{read_only:false; response_revision:2128; number_of_response:1; }","duration":"1.52367254s","start":"2026-08-22T10:36:00.502129Z","end":"2026-08-22T10:36:02.025802Z","steps":["trace[1928256198] 'process raft request' (duration: 1.52340717s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.025865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.496818Z","time spent":"1.529040581s","remote":"127.0.0.1:59818","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-08-22T10:36:02.025934Z","caller":"traceutil/trace.go:171","msg":"trace[1792278650] linearizableReadLoop","detail":"{readStateIndex:2217; appliedIndex:2212; }","duration":"1.520288343s","start":"2026-08-22T10:36:00.505633Z","end":"2026-08-22T10:36:02.025922Z","steps":["trace[1792278650] 'read index received' (duration: 292.934829ms)","trace[1792278650] 'applied index is now lower than readState.Index' (duration: 1.227352234s)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:36:02.025953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.502125Z","time spent":"1.523755313s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:02.025943Z","caller":"traceutil/trace.go:171","msg":"trace[1289515508] transaction","detail":"{read_only:false; response_revision:2130; number_of_response:1; }","duration":"1.52289685s","start":"2026-08-22T10:36:00.503019Z","end":"2026-08-22T10:36:02.025915Z","steps":["trace[1289515508] 'process raft request' (duration: 1.522751555s)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:02.025886Z","caller":"traceutil/trace.go:171","msg":"trace[918447602] transaction","detail":"{read_only:false; response_revision:2131; number_of_response:1; }","duration":"955.318362ms","start":"2026-08-22T10:36:01.070551Z","end":"2026-08-22T10:36:02.025869Z","steps":["trace[918447602] 'process raft request' (duration: 955.26868ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.026084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.520445788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:36:02.026097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.503009Z","time spent":"1.523004764s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:02.026157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:01.070532Z","time spent":"955.544699ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":508,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:02.026135Z","caller":"traceutil/trace.go:171","msg":"trace[1124863901] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2131; }","duration":"1.520523691s","start":"2026-08-22T10:36:00.505599Z","end":"2026-08-22T10:36:02.026122Z","steps":["trace[1124863901] 'agreement among raft nodes before linearized reading' (duration: 1.520411667s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.026318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.505586Z","time spent":"1.520716968s","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:36:02.026384Z","caller":"traceutil/trace.go:171","msg":"trace[1276184548] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"1.523415369s","start":"2026-08-22T10:36:00.502956Z","end":"2026-08-22T10:36:02.026371Z","steps":["trace[1276184548] 'process raft request' (duration: 1.522740374s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.026456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.502949Z","time spent":"1.523472601s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:36:02.025618Z","caller":"traceutil/trace.go:171","msg":"trace[1707312113] transaction","detail":"{read_only:false; response_revision:2127; number_of_response:1; }","duration":"1.523536105s","start":"2026-08-22T10:36:00.502065Z","end":"2026-08-22T10:36:02.025601Z","steps":["trace[1707312113] 'process raft request' (duration: 1.523338818s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.026766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.502046Z","time spent":"1.524670197s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:36:02.048346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.787453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12382"} {"level":"warn","ts":"2026-08-22T10:36:02.048391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.464563ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:36:02.048424Z","caller":"traceutil/trace.go:171","msg":"trace[355672121] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2133; }","duration":"424.915627ms","start":"2026-08-22T10:36:01.623492Z","end":"2026-08-22T10:36:02.048407Z","steps":["trace[355672121] 'agreement among raft nodes before linearized reading' (duration: 424.71872ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:36:02.048444Z","caller":"traceutil/trace.go:171","msg":"trace[459544953] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2133; }","duration":"545.561127ms","start":"2026-08-22T10:36:01.50287Z","end":"2026-08-22T10:36:02.048431Z","steps":["trace[459544953] 'agreement among raft nodes before linearized reading' (duration: 545.411221ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.048478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:01.623463Z","time spent":"425.00632ms","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12406,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-22T10:36:02.048534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.142945562s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:36:02.048584Z","caller":"traceutil/trace.go:171","msg":"trace[721410180] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2133; }","duration":"1.142995134s","start":"2026-08-22T10:36:00.905577Z","end":"2026-08-22T10:36:02.048572Z","steps":["trace[721410180] 'agreement among raft nodes before linearized reading' (duration: 1.142926282s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.048622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.905522Z","time spent":"1.143087878s","remote":"127.0.0.1:46178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:36:02.048757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.176056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:36:02.048799Z","caller":"traceutil/trace.go:171","msg":"trace[506029988] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2133; }","duration":"101.256628ms","start":"2026-08-22T10:36:01.947531Z","end":"2026-08-22T10:36:02.048787Z","steps":["trace[506029988] 'agreement among raft nodes before linearized reading' (duration: 101.194626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.0488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431652852s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:36:02.048971Z","caller":"traceutil/trace.go:171","msg":"trace[981650958] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2133; }","duration":"1.43186439s","start":"2026-08-22T10:36:00.617093Z","end":"2026-08-22T10:36:02.048958Z","steps":["trace[981650958] 'agreement among raft nodes before linearized reading' (duration: 1.431677273s)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.049019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:00.617017Z","time spent":"1.431987155s","remote":"127.0.0.1:60038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-22T10:36:02.04848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:01.502849Z","time spent":"545.622029ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-22T10:36:02.884145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.925089ms","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-08-22T10:36:02.884305Z","caller":"traceutil/trace.go:171","msg":"trace[977883466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2134; }","duration":"383.126167ms","start":"2026-08-22T10:36:02.501163Z","end":"2026-08-22T10:36:02.884289Z","steps":["trace[977883466] 'range keys from in-memory index tree' (duration: 382.798174ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.884418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:02.501144Z","time spent":"383.261141ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:36:02.884177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.303838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-08-22T10:36:02.884886Z","caller":"traceutil/trace.go:171","msg":"trace[566870622] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2134; }","duration":"749.056376ms","start":"2026-08-22T10:36:02.135815Z","end":"2026-08-22T10:36:02.884871Z","steps":["trace[566870622] 'range keys from in-memory index tree' (duration: 748.133802ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.884958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:02.135806Z","time spent":"749.132948ms","remote":"127.0.0.1:34230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5436,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-08-22T10:36:02.946144Z","caller":"traceutil/trace.go:171","msg":"trace[329319940] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"443.438386ms","start":"2026-08-22T10:36:02.502686Z","end":"2026-08-22T10:36:02.946124Z","steps":["trace[329319940] 'process raft request' (duration: 443.248329ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:36:02.946323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:36:02.502661Z","time spent":"443.5947ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:37:11.951667Z","caller":"traceutil/trace.go:171","msg":"trace[1172886018] transaction","detail":"{read_only:false; response_revision:2606; number_of_response:1; }","duration":"259.798731ms","start":"2026-08-22T10:37:11.691841Z","end":"2026-08-22T10:37:11.95164Z","steps":["trace[1172886018] 'process raft request' (duration: 259.599006ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:38.689159Z","caller":"traceutil/trace.go:171","msg":"trace[604144355] transaction","detail":"{read_only:false; response_revision:2759; number_of_response:1; }","duration":"180.916137ms","start":"2026-08-22T10:37:38.508224Z","end":"2026-08-22T10:37:38.68914Z","steps":["trace[604144355] 'process raft request' (duration: 85.116001ms)","trace[604144355] 'compare' (duration: 95.675393ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:37:44.56774Z","caller":"traceutil/trace.go:171","msg":"trace[1528886286] transaction","detail":"{read_only:false; response_revision:2784; number_of_response:1; }","duration":"152.63124ms","start":"2026-08-22T10:37:44.415079Z","end":"2026-08-22T10:37:44.56771Z","steps":["trace[1528886286] 'process raft request' (duration: 152.465086ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:44.585382Z","caller":"traceutil/trace.go:171","msg":"trace[1296442005] transaction","detail":"{read_only:false; response_revision:2785; number_of_response:1; }","duration":"139.496472ms","start":"2026-08-22T10:37:44.445865Z","end":"2026-08-22T10:37:44.585362Z","steps":["trace[1296442005] 'process raft request' (duration: 139.354669ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:44.585593Z","caller":"traceutil/trace.go:171","msg":"trace[2129125399] transaction","detail":"{read_only:false; response_revision:2786; number_of_response:1; }","duration":"139.610345ms","start":"2026-08-22T10:37:44.445907Z","end":"2026-08-22T10:37:44.585517Z","steps":["trace[2129125399] 'process raft request' (duration: 139.39739ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:44.716656Z","caller":"traceutil/trace.go:171","msg":"trace[367926004] transaction","detail":"{read_only:false; response_revision:2787; number_of_response:1; }","duration":"126.885597ms","start":"2026-08-22T10:37:44.589739Z","end":"2026-08-22T10:37:44.716625Z","steps":["trace[367926004] 'process raft request' (duration: 93.879909ms)","trace[367926004] 'compare' (duration: 32.733151ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:37:44.717148Z","caller":"traceutil/trace.go:171","msg":"trace[267719694] transaction","detail":"{read_only:false; response_revision:2788; number_of_response:1; }","duration":"126.742514ms","start":"2026-08-22T10:37:44.590371Z","end":"2026-08-22T10:37:44.717113Z","steps":["trace[267719694] 'process raft request' (duration: 126.200811ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:44.717699Z","caller":"traceutil/trace.go:171","msg":"trace[1830450546] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"126.926689ms","start":"2026-08-22T10:37:44.590748Z","end":"2026-08-22T10:37:44.717674Z","steps":["trace[1830450546] 'process raft request' (duration: 126.19402ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:44.718122Z","caller":"traceutil/trace.go:171","msg":"trace[71119851] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"123.075025ms","start":"2026-08-22T10:37:44.595036Z","end":"2026-08-22T10:37:44.718111Z","steps":["trace[71119851] 'process raft request' (duration: 122.553473ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.53341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.899316ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:37:45.533563Z","caller":"traceutil/trace.go:171","msg":"trace[1931870241] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"810.312917ms","start":"2026-08-22T10:37:44.723238Z","end":"2026-08-22T10:37:45.533551Z","steps":["trace[1931870241] 'process raft request' (duration: 810.261096ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:45.533566Z","caller":"traceutil/trace.go:171","msg":"trace[1660162728] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"812.783288ms","start":"2026-08-22T10:37:44.720747Z","end":"2026-08-22T10:37:45.53353Z","steps":["trace[1660162728] 'process raft request' (duration: 302.682377ms)","trace[1660162728] 'compare' (duration: 509.821264ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:37:45.533607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:44.723211Z","time spent":"810.373889ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:37:45.533624Z","caller":"traceutil/trace.go:171","msg":"trace[1174702613] linearizableReadLoop","detail":"{readStateIndex:2901; appliedIndex:2900; }","duration":"811.988629ms","start":"2026-08-22T10:37:44.721587Z","end":"2026-08-22T10:37:45.533575Z","steps":["trace[1174702613] 'read index received' (duration: 302.057452ms)","trace[1174702613] 'applied index is now lower than readState.Index' (duration: 509.926187ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:37:45.533782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:44.720725Z","time spent":"812.90699ms","remote":"127.0.0.1:59818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":766,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:37:45.534053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"812.417109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:37:45.534112Z","caller":"traceutil/trace.go:171","msg":"trace[98584676] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:2792; }","duration":"812.535731ms","start":"2026-08-22T10:37:44.721559Z","end":"2026-08-22T10:37:45.534095Z","steps":["trace[98584676] 'agreement among raft nodes before linearized reading' (duration: 812.228344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.534332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:44.721543Z","time spent":"812.766397ms","remote":"127.0.0.1:59772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-08-22T10:37:45.882947Z","caller":"traceutil/trace.go:171","msg":"trace[1281052655] linearizableReadLoop","detail":"{readStateIndex:2903; appliedIndex:2902; }","duration":"349.189855ms","start":"2026-08-22T10:37:45.533718Z","end":"2026-08-22T10:37:45.882908Z","steps":["trace[1281052655] 'read index received' (duration: 348.976818ms)","trace[1281052655] 'applied index is now lower than readState.Index' (duration: 212.186µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:37:45.882944Z","caller":"traceutil/trace.go:171","msg":"trace[1289372388] transaction","detail":"{read_only:false; response_revision:2793; number_of_response:1; }","duration":"507.879498ms","start":"2026-08-22T10:37:45.375044Z","end":"2026-08-22T10:37:45.882924Z","steps":["trace[1289372388] 'process raft request' (duration: 507.717043ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.883109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:45.375017Z","time spent":"508.021242ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:37:45.883182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.551466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"warn","ts":"2026-08-22T10:37:45.883211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.223841ms","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-08-22T10:37:45.883231Z","caller":"traceutil/trace.go:171","msg":"trace[542730930] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2793; }","duration":"506.628647ms","start":"2026-08-22T10:37:45.37659Z","end":"2026-08-22T10:37:45.883218Z","steps":["trace[542730930] 'agreement among raft nodes before linearized reading' (duration: 506.491764ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:37:45.883245Z","caller":"traceutil/trace.go:171","msg":"trace[1033375091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2793; }","duration":"365.314992ms","start":"2026-08-22T10:37:45.517919Z","end":"2026-08-22T10:37:45.883234Z","steps":["trace[1033375091] 'agreement among raft nodes before linearized reading' (duration: 365.127828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.883254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:45.376582Z","time spent":"506.667679ms","remote":"127.0.0.1:59954","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1393,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-22T10:37:45.88328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:45.51791Z","time spent":"365.362594ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:37:45.883358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.082913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:37:45.883329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.851618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-08-22T10:37:45.883449Z","caller":"traceutil/trace.go:171","msg":"trace[528017506] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2793; }","duration":"347.006172ms","start":"2026-08-22T10:37:45.536418Z","end":"2026-08-22T10:37:45.883424Z","steps":["trace[528017506] 'agreement among raft nodes before linearized reading' (duration: 346.783296ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.883497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:45.536395Z","time spent":"347.092763ms","remote":"127.0.0.1:60036","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":505,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"info","ts":"2026-08-22T10:37:45.883387Z","caller":"traceutil/trace.go:171","msg":"trace[1776692562] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2793; }","duration":"376.141044ms","start":"2026-08-22T10:37:45.50724Z","end":"2026-08-22T10:37:45.883381Z","steps":["trace[1776692562] 'agreement among raft nodes before linearized reading' (duration: 376.090193ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:37:45.883789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:37:45.507223Z","time spent":"376.513553ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:38:09.799216Z","caller":"traceutil/trace.go:171","msg":"trace[1687856614] linearizableReadLoop","detail":"{readStateIndex:3034; appliedIndex:3033; }","duration":"123.158166ms","start":"2026-08-22T10:38:09.676029Z","end":"2026-08-22T10:38:09.799188Z","steps":["trace[1687856614] 'read index received' (duration: 122.981712ms)","trace[1687856614] 'applied index is now lower than readState.Index' (duration: 175.094µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:38:09.799268Z","caller":"traceutil/trace.go:171","msg":"trace[211889688] transaction","detail":"{read_only:false; response_revision:2919; number_of_response:1; }","duration":"132.170977ms","start":"2026-08-22T10:38:09.667056Z","end":"2026-08-22T10:38:09.799227Z","steps":["trace[211889688] 'process raft request' (duration: 131.974963ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:38:09.799471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.429362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T10:38:09.79963Z","caller":"traceutil/trace.go:171","msg":"trace[1243055133] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2919; }","duration":"123.651247ms","start":"2026-08-22T10:38:09.675963Z","end":"2026-08-22T10:38:09.799615Z","steps":["trace[1243055133] 'agreement among raft nodes before linearized reading' (duration: 123.32922ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:44.626655Z","caller":"traceutil/trace.go:171","msg":"trace[1091110915] linearizableReadLoop","detail":"{readStateIndex:4823; appliedIndex:4822; }","duration":"118.672899ms","start":"2026-08-22T10:41:44.507957Z","end":"2026-08-22T10:41:44.62663Z","steps":["trace[1091110915] 'read index received' (duration: 117.649842ms)","trace[1091110915] 'applied index is now lower than readState.Index' (duration: 1.021896ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:44.626911Z","caller":"traceutil/trace.go:171","msg":"trace[162641323] transaction","detail":"{read_only:false; response_revision:4642; number_of_response:1; }","duration":"163.063704ms","start":"2026-08-22T10:41:44.463834Z","end":"2026-08-22T10:41:44.626898Z","steps":["trace[162641323] 'process raft request' (duration: 161.943447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:44.627162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.183686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:44.627197Z","caller":"traceutil/trace.go:171","msg":"trace[2018779390] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4642; }","duration":"119.282588ms","start":"2026-08-22T10:41:44.507908Z","end":"2026-08-22T10:41:44.62719Z","steps":["trace[2018779390] 'agreement among raft nodes before linearized reading' (duration: 119.180246ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:44.627421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.012419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.105\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-22T10:41:44.627453Z","caller":"traceutil/trace.go:171","msg":"trace[406781823] range","detail":"{range_begin:/registry/masterleases/199.19.213.105; range_end:; response_count:1; response_revision:4642; }","duration":"101.07118ms","start":"2026-08-22T10:41:44.526375Z","end":"2026-08-22T10:41:44.627446Z","steps":["trace[406781823] 'agreement among raft nodes before linearized reading' (duration: 100.946188ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:45.569811Z","caller":"traceutil/trace.go:171","msg":"trace[1288286917] transaction","detail":"{read_only:false; response_revision:4681; number_of_response:1; }","duration":"253.201873ms","start":"2026-08-22T10:41:45.316577Z","end":"2026-08-22T10:41:45.569779Z","steps":["trace[1288286917] 'process raft request' (duration: 252.935489ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:45.569932Z","caller":"traceutil/trace.go:171","msg":"trace[1545304919] linearizableReadLoop","detail":"{readStateIndex:4863; appliedIndex:4862; }","duration":"250.638015ms","start":"2026-08-22T10:41:45.319239Z","end":"2026-08-22T10:41:45.569877Z","steps":["trace[1545304919] 'read index received' (duration: 250.289109ms)","trace[1545304919] 'applied index is now lower than readState.Index' (duration: 345.576µs)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:45.570257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.976891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:3506"} {"level":"warn","ts":"2026-08-22T10:41:45.570473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.085295ms","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-08-22T10:41:45.570537Z","caller":"traceutil/trace.go:171","msg":"trace[1244911050] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4681; }","duration":"129.233277ms","start":"2026-08-22T10:41:45.441289Z","end":"2026-08-22T10:41:45.570522Z","steps":["trace[1244911050] 'agreement among raft nodes before linearized reading' (duration: 129.005514ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:45.571072Z","caller":"traceutil/trace.go:171","msg":"trace[476658096] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:4681; }","duration":"251.860574ms","start":"2026-08-22T10:41:45.31918Z","end":"2026-08-22T10:41:45.57104Z","steps":["trace[476658096] 'agreement among raft nodes before linearized reading' (duration: 250.868119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:45.570225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.01233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:45.571377Z","caller":"traceutil/trace.go:171","msg":"trace[1058754812] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:4681; }","duration":"179.217798ms","start":"2026-08-22T10:41:45.392108Z","end":"2026-08-22T10:41:45.571326Z","steps":["trace[1058754812] 'agreement among raft nodes before linearized reading' (duration: 178.00445ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:46.33375Z","caller":"traceutil/trace.go:171","msg":"trace[2070912380] linearizableReadLoop","detail":"{readStateIndex:4873; appliedIndex:4872; }","duration":"195.770578ms","start":"2026-08-22T10:41:46.137951Z","end":"2026-08-22T10:41:46.333722Z","steps":["trace[2070912380] 'read index received' (duration: 195.514044ms)","trace[2070912380] 'applied index is now lower than readState.Index' (duration: 254.864µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:46.333827Z","caller":"traceutil/trace.go:171","msg":"trace[1143177390] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"199.967671ms","start":"2026-08-22T10:41:46.13381Z","end":"2026-08-22T10:41:46.333778Z","steps":["trace[1143177390] 'process raft request' (duration: 199.724238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:46.334041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.859025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e2eb398f-44b7-4d8d-b691-2c9cd18ac45b\" ","response":"range_response_count:1 size:3894"} {"level":"warn","ts":"2026-08-22T10:41:46.334042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.058542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-22T10:41:46.334089Z","caller":"traceutil/trace.go:171","msg":"trace[1353663931] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e2eb398f-44b7-4d8d-b691-2c9cd18ac45b; range_end:; response_count:1; response_revision:4690; }","duration":"177.998137ms","start":"2026-08-22T10:41:46.156078Z","end":"2026-08-22T10:41:46.334076Z","steps":["trace[1353663931] 'agreement among raft nodes before linearized reading' (duration: 177.810254ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:46.334102Z","caller":"traceutil/trace.go:171","msg":"trace[1995835635] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4690; }","duration":"196.158293ms","start":"2026-08-22T10:41:46.137927Z","end":"2026-08-22T10:41:46.334085Z","steps":["trace[1995835635] 'agreement among raft nodes before linearized reading' (duration: 195.95328ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:46.334193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.94964ms","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-08-22T10:41:46.334282Z","caller":"traceutil/trace.go:171","msg":"trace[2113509939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4690; }","duration":"148.109893ms","start":"2026-08-22T10:41:46.18615Z","end":"2026-08-22T10:41:46.33426Z","steps":["trace[2113509939] 'agreement among raft nodes before linearized reading' (duration: 147.848499ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:46.543119Z","caller":"traceutil/trace.go:171","msg":"trace[1792079029] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"111.208072ms","start":"2026-08-22T10:41:46.431884Z","end":"2026-08-22T10:41:46.543092Z","steps":["trace[1792079029] 'process raft request' (duration: 110.615623ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:47.178642Z","caller":"traceutil/trace.go:171","msg":"trace[1638379740] linearizableReadLoop","detail":"{readStateIndex:4881; appliedIndex:4880; }","duration":"210.249765ms","start":"2026-08-22T10:41:46.968363Z","end":"2026-08-22T10:41:47.178612Z","steps":["trace[1638379740] 'read index received' (duration: 131.887495ms)","trace[1638379740] 'applied index is now lower than readState.Index' (duration: 78.3609ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:47.178665Z","caller":"traceutil/trace.go:171","msg":"trace[1182706744] transaction","detail":"{read_only:false; response_revision:4698; number_of_response:1; }","duration":"213.16431ms","start":"2026-08-22T10:41:46.965463Z","end":"2026-08-22T10:41:47.178627Z","steps":["trace[1182706744] 'process raft request' (duration: 134.87233ms)","trace[1182706744] 'compare' (duration: 78.132237ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:47.178867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.721083ms","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-08-22T10:41:47.178911Z","caller":"traceutil/trace.go:171","msg":"trace[1092043605] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:4698; }","duration":"187.818784ms","start":"2026-08-22T10:41:46.991081Z","end":"2026-08-22T10:41:47.1789Z","steps":["trace[1092043605] 'agreement among raft nodes before linearized reading' (duration: 187.707042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:47.17905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.667912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-22T10:41:47.179124Z","caller":"traceutil/trace.go:171","msg":"trace[77026076] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4698; }","duration":"210.796574ms","start":"2026-08-22T10:41:46.968308Z","end":"2026-08-22T10:41:47.179104Z","steps":["trace[77026076] 'agreement among raft nodes before linearized reading' (duration: 210.415468ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:47.959383Z","caller":"traceutil/trace.go:171","msg":"trace[738966269] transaction","detail":"{read_only:false; response_revision:4737; number_of_response:1; }","duration":"122.801535ms","start":"2026-08-22T10:41:47.836536Z","end":"2026-08-22T10:41:47.959338Z","steps":["trace[738966269] 'process raft request' (duration: 107.905759ms)","trace[738966269] 'compare' (duration: 14.685173ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:47.959824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.429839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-22T10:41:47.959947Z","caller":"traceutil/trace.go:171","msg":"trace[403354846] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4737; }","duration":"110.563171ms","start":"2026-08-22T10:41:47.849327Z","end":"2026-08-22T10:41:47.95989Z","steps":["trace[403354846] 'agreement among raft nodes before linearized reading' (duration: 109.992432ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:48.996202Z","caller":"traceutil/trace.go:171","msg":"trace[919060056] transaction","detail":"{read_only:false; response_revision:4748; number_of_response:1; }","duration":"197.956646ms","start":"2026-08-22T10:41:48.798222Z","end":"2026-08-22T10:41:48.996179Z","steps":["trace[919060056] 'process raft request' (duration: 197.821714ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:49.04873Z","caller":"traceutil/trace.go:171","msg":"trace[1762154315] linearizableReadLoop","detail":"{readStateIndex:4934; appliedIndex:4933; }","duration":"193.983185ms","start":"2026-08-22T10:41:48.854721Z","end":"2026-08-22T10:41:49.048704Z","steps":["trace[1762154315] 'read index received' (duration: 141.447029ms)","trace[1762154315] 'applied index is now lower than readState.Index' (duration: 52.522146ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:49.048894Z","caller":"traceutil/trace.go:171","msg":"trace[975249179] transaction","detail":"{read_only:false; response_revision:4749; number_of_response:1; }","duration":"243.877722ms","start":"2026-08-22T10:41:48.804999Z","end":"2026-08-22T10:41:49.048877Z","steps":["trace[975249179] 'process raft request' (duration: 243.546697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:49.04923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.499073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12212"} {"level":"info","ts":"2026-08-22T10:41:49.049331Z","caller":"traceutil/trace.go:171","msg":"trace[1902853989] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4749; }","duration":"194.600105ms","start":"2026-08-22T10:41:48.854663Z","end":"2026-08-22T10:41:49.049263Z","steps":["trace[1902853989] 'agreement among raft nodes before linearized reading' (duration: 194.426981ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:53.996109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.630425ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:41:53.996314Z","caller":"traceutil/trace.go:171","msg":"trace[1722806327] linearizableReadLoop","detail":"{readStateIndex:5048; appliedIndex:5046; }","duration":"783.779893ms","start":"2026-08-22T10:41:53.212515Z","end":"2026-08-22T10:41:53.996295Z","steps":["trace[1722806327] 'read index received' (duration: 12.864214ms)","trace[1722806327] 'applied index is now lower than readState.Index' (duration: 770.914829ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:41:53.996387Z","caller":"traceutil/trace.go:171","msg":"trace[1151852664] transaction","detail":"{read_only:false; response_revision:4859; number_of_response:1; }","duration":"816.837603ms","start":"2026-08-22T10:41:53.179538Z","end":"2026-08-22T10:41:53.996376Z","steps":["trace[1151852664] 'process raft request' (duration: 45.825492ms)","trace[1151852664] 'compare' (duration: 770.318391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:53.996456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.17951Z","time spent":"816.906285ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:41:53.996481Z","caller":"traceutil/trace.go:171","msg":"trace[1110952687] transaction","detail":"{read_only:false; response_revision:4860; number_of_response:1; }","duration":"797.618732ms","start":"2026-08-22T10:41:53.198829Z","end":"2026-08-22T10:41:53.996448Z","steps":["trace[1110952687] 'process raft request' (duration: 797.397579ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:53.996645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.198801Z","time spent":"797.749836ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:41:53.996851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.329892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:41:53.996896Z","caller":"traceutil/trace.go:171","msg":"trace[1249609289] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4860; }","duration":"784.403904ms","start":"2026-08-22T10:41:53.212478Z","end":"2026-08-22T10:41:53.996882Z","steps":["trace[1249609289] 'agreement among raft nodes before linearized reading' (duration: 784.17595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:53.99694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.212458Z","time spent":"784.473095ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-22T10:41:54.014951Z","caller":"traceutil/trace.go:171","msg":"trace[641828611] transaction","detail":"{read_only:false; response_revision:4861; number_of_response:1; }","duration":"304.77147ms","start":"2026-08-22T10:41:53.710134Z","end":"2026-08-22T10:41:54.014906Z","steps":["trace[641828611] 'process raft request' (duration: 304.679808ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.015105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.710104Z","time spent":"304.950682ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:41:54.151829Z","caller":"traceutil/trace.go:171","msg":"trace[2038325341] transaction","detail":"{read_only:false; response_revision:4862; number_of_response:1; }","duration":"425.376562ms","start":"2026-08-22T10:41:53.726425Z","end":"2026-08-22T10:41:54.151802Z","steps":["trace[2038325341] 'process raft request' (duration: 425.151878ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.152017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.726393Z","time spent":"425.518204ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:41:54.152268Z","caller":"traceutil/trace.go:171","msg":"trace[955113940] linearizableReadLoop","detail":"{readStateIndex:5051; appliedIndex:5049; }","duration":"155.895066ms","start":"2026-08-22T10:41:53.996342Z","end":"2026-08-22T10:41:54.152237Z","steps":["trace[955113940] 'read index received' (duration: 18.622102ms)","trace[955113940] 'applied index is now lower than readState.Index' (duration: 137.271534ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:41:54.152418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"644.255162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T10:41:54.152463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.788564ms","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-08-22T10:41:54.152495Z","caller":"traceutil/trace.go:171","msg":"trace[2129486995] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4864; }","duration":"644.380424ms","start":"2026-08-22T10:41:53.508099Z","end":"2026-08-22T10:41:54.152479Z","steps":["trace[2129486995] 'agreement among raft nodes before linearized reading' (duration: 644.220951ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.152507Z","caller":"traceutil/trace.go:171","msg":"trace[1916347569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4864; }","duration":"440.868506ms","start":"2026-08-22T10:41:53.711626Z","end":"2026-08-22T10:41:54.152494Z","steps":["trace[1916347569] 'agreement among raft nodes before linearized reading' (duration: 440.664993ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.15251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.286794ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48427613-3be8-44f6-8fd4-a94efa6428c1\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-08-22T10:41:54.152552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.711602Z","time spent":"440.942057ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:41:54.152555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.508085Z","time spent":"644.460796ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:41:54.152567Z","caller":"traceutil/trace.go:171","msg":"trace[1551889296] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48427613-3be8-44f6-8fd4-a94efa6428c1; range_end:; response_count:1; response_revision:4864; }","duration":"771.403145ms","start":"2026-08-22T10:41:53.381141Z","end":"2026-08-22T10:41:54.152544Z","steps":["trace[1551889296] 'agreement among raft nodes before linearized reading' (duration: 771.209772ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.152651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.381113Z","time spent":"771.518357ms","remote":"127.0.0.1:59948","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3670,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48427613-3be8-44f6-8fd4-a94efa6428c1\" "} {"level":"warn","ts":"2026-08-22T10:41:54.152673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.744002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-22T10:41:54.152625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.995043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-48427613-3be8-44f6-8fd4-a94efa6428c1\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-22T10:41:54.152707Z","caller":"traceutil/trace.go:171","msg":"trace[1586054505] range","detail":"{range_begin:/registry/cert-manager.io/issuers/; range_end:/registry/cert-manager.io/issuers0; response_count:0; response_revision:4864; }","duration":"389.815524ms","start":"2026-08-22T10:41:53.762879Z","end":"2026-08-22T10:41:54.152695Z","steps":["trace[1586054505] 'agreement among raft nodes before linearized reading' (duration: 389.758923ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.152747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.76286Z","time spent":"389.879504ms","remote":"127.0.0.1:49724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":31,"request content":"key:\"/registry/cert-manager.io/issuers/\" range_end:\"/registry/cert-manager.io/issuers0\" count_only:true "} {"level":"info","ts":"2026-08-22T10:41:54.152763Z","caller":"traceutil/trace.go:171","msg":"trace[1246204193] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-48427613-3be8-44f6-8fd4-a94efa6428c1; range_end:; response_count:1; response_revision:4864; }","duration":"280.157325ms","start":"2026-08-22T10:41:53.872555Z","end":"2026-08-22T10:41:54.152712Z","steps":["trace[1246204193] 'agreement among raft nodes before linearized reading' (duration: 279.710328ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.153104Z","caller":"traceutil/trace.go:171","msg":"trace[1010853433] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"150.746318ms","start":"2026-08-22T10:41:54.002344Z","end":"2026-08-22T10:41:54.15309Z","steps":["trace[1010853433] 'process raft request' (duration: 149.715402ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.152264Z","caller":"traceutil/trace.go:171","msg":"trace[16738553] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"417.670035ms","start":"2026-08-22T10:41:53.734558Z","end":"2026-08-22T10:41:54.152228Z","steps":["trace[16738553] 'process raft request' (duration: 417.195078ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.153453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:41:53.734537Z","time spent":"418.829933ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:41:54.567659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.484777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T10:41:54.567749Z","caller":"traceutil/trace.go:171","msg":"trace[1716383675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4867; }","duration":"141.62917ms","start":"2026-08-22T10:41:54.4261Z","end":"2026-08-22T10:41:54.567729Z","steps":["trace[1716383675] 'range keys from in-memory index tree' (duration: 141.299965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:54.567815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.029101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T10:41:54.567933Z","caller":"traceutil/trace.go:171","msg":"trace[213648772] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4867; }","duration":"141.215903ms","start":"2026-08-22T10:41:54.426693Z","end":"2026-08-22T10:41:54.567909Z","steps":["trace[213648772] 'range keys from in-memory index tree' (duration: 140.797348ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.858217Z","caller":"traceutil/trace.go:171","msg":"trace[452881072] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"285.86157ms","start":"2026-08-22T10:41:54.572338Z","end":"2026-08-22T10:41:54.858199Z","steps":["trace[452881072] 'process raft request' (duration: 285.82569ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:41:54.858517Z","caller":"traceutil/trace.go:171","msg":"trace[1995758866] transaction","detail":"{read_only:false; response_revision:4868; number_of_response:1; }","duration":"286.172915ms","start":"2026-08-22T10:41:54.572291Z","end":"2026-08-22T10:41:54.858464Z","steps":["trace[1995758866] 'process raft request' (duration: 285.762609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:41:59.427316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.105217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:41:59.427426Z","caller":"traceutil/trace.go:171","msg":"trace[336172526] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4951; }","duration":"266.268558ms","start":"2026-08-22T10:41:59.161131Z","end":"2026-08-22T10:41:59.4274Z","steps":["trace[336172526] 'range keys from in-memory index tree' (duration: 266.014595ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:04.22764Z","caller":"traceutil/trace.go:171","msg":"trace[1176865178] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"154.234493ms","start":"2026-08-22T10:42:04.073384Z","end":"2026-08-22T10:42:04.227619Z","steps":["trace[1176865178] 'process raft request' (duration: 152.818481ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:04.233525Z","caller":"traceutil/trace.go:171","msg":"trace[1840879921] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"155.788457ms","start":"2026-08-22T10:42:04.077719Z","end":"2026-08-22T10:42:04.233507Z","steps":["trace[1840879921] 'process raft request' (duration: 153.964199ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:04.234094Z","caller":"traceutil/trace.go:171","msg":"trace[1016849283] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"101.639215ms","start":"2026-08-22T10:42:04.132433Z","end":"2026-08-22T10:42:04.234072Z","steps":["trace[1016849283] 'process raft request' (duration: 100.921344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.416445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.017088ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-22T10:42:10.416579Z","caller":"traceutil/trace.go:171","msg":"trace[1064153652] linearizableReadLoop","detail":"{readStateIndex:5271; appliedIndex:5270; }","duration":"373.778773ms","start":"2026-08-22T10:42:10.042778Z","end":"2026-08-22T10:42:10.416556Z","steps":["trace[1064153652] 'read index received' (duration: 254.687454ms)","trace[1064153652] 'applied index is now lower than readState.Index' (duration: 119.083699ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:10.416999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.208389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-08-22T10:42:10.417067Z","caller":"traceutil/trace.go:171","msg":"trace[1149347277] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:5077; }","duration":"374.331301ms","start":"2026-08-22T10:42:10.042709Z","end":"2026-08-22T10:42:10.41704Z","steps":["trace[1149347277] 'agreement among raft nodes before linearized reading' (duration: 374.132368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.417135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:10.042679Z","time spent":"374.445853ms","remote":"127.0.0.1:59916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2106,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"info","ts":"2026-08-22T10:42:10.422328Z","caller":"traceutil/trace.go:171","msg":"trace[757658045] transaction","detail":"{read_only:false; response_revision:5078; number_of_response:1; }","duration":"344.297743ms","start":"2026-08-22T10:42:10.077986Z","end":"2026-08-22T10:42:10.422283Z","steps":["trace[757658045] 'process raft request' (duration: 344.003278ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:10.422592Z","caller":"traceutil/trace.go:171","msg":"trace[640359181] transaction","detail":"{read_only:false; response_revision:5080; number_of_response:1; }","duration":"154.628989ms","start":"2026-08-22T10:42:10.26782Z","end":"2026-08-22T10:42:10.422449Z","steps":["trace[640359181] 'process raft request' (duration: 154.406106ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:10.422843Z","caller":"traceutil/trace.go:171","msg":"trace[332236389] transaction","detail":"{read_only:false; response_revision:5081; number_of_response:1; }","duration":"152.907313ms","start":"2026-08-22T10:42:10.269905Z","end":"2026-08-22T10:42:10.422813Z","steps":["trace[332236389] 'process raft request' (duration: 152.491237ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.423246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.395951ms","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-08-22T10:42:10.423301Z","caller":"traceutil/trace.go:171","msg":"trace[338790083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5081; }","duration":"149.515033ms","start":"2026-08-22T10:42:10.273773Z","end":"2026-08-22T10:42:10.423288Z","steps":["trace[338790083] 'agreement among raft nodes before linearized reading' (duration: 149.29752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.423594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.440038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:42:10.423708Z","caller":"traceutil/trace.go:171","msg":"trace[790141560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5081; }","duration":"139.065657ms","start":"2026-08-22T10:42:10.284622Z","end":"2026-08-22T10:42:10.423688Z","steps":["trace[790141560] 'agreement among raft nodes before linearized reading' (duration: 138.300305ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:10.422328Z","caller":"traceutil/trace.go:171","msg":"trace[567536635] transaction","detail":"{read_only:false; response_revision:5079; number_of_response:1; }","duration":"155.281279ms","start":"2026-08-22T10:42:10.267017Z","end":"2026-08-22T10:42:10.422299Z","steps":["trace[567536635] 'process raft request' (duration: 155.155837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.422874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:10.077955Z","time spent":"344.617598ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:10.597095Z","caller":"traceutil/trace.go:171","msg":"trace[1594402701] transaction","detail":"{read_only:false; response_revision:5082; number_of_response:1; }","duration":"168.563737ms","start":"2026-08-22T10:42:10.42844Z","end":"2026-08-22T10:42:10.597004Z","steps":["trace[1594402701] 'process raft request' (duration: 96.167057ms)","trace[1594402701] 'compare' (duration: 72.074896ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:10.597178Z","caller":"traceutil/trace.go:171","msg":"trace[498743893] linearizableReadLoop","detail":"{readStateIndex:5277; appliedIndex:5275; }","duration":"167.501031ms","start":"2026-08-22T10:42:10.429634Z","end":"2026-08-22T10:42:10.597135Z","steps":["trace[498743893] 'read index received' (duration: 95.06844ms)","trace[498743893] 'applied index is now lower than readState.Index' (duration: 72.429021ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:42:10.597274Z","caller":"traceutil/trace.go:171","msg":"trace[1259691958] transaction","detail":"{read_only:false; response_revision:5083; number_of_response:1; }","duration":"167.965698ms","start":"2026-08-22T10:42:10.42929Z","end":"2026-08-22T10:42:10.597255Z","steps":["trace[1259691958] 'process raft request' (duration: 167.653343ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.597552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.889138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-48427613-3be8-44f6-8fd4-a94efa6428c1\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-08-22T10:42:10.597601Z","caller":"traceutil/trace.go:171","msg":"trace[352860148] range","detail":"{range_begin:/registry/persistentvolumes/pvc-48427613-3be8-44f6-8fd4-a94efa6428c1; range_end:; response_count:1; response_revision:5083; }","duration":"167.971239ms","start":"2026-08-22T10:42:10.429616Z","end":"2026-08-22T10:42:10.597587Z","steps":["trace[352860148] 'agreement among raft nodes before linearized reading' (duration: 167.687225ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.597905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.989767ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:42:10.598002Z","caller":"traceutil/trace.go:171","msg":"trace[397225164] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5083; }","duration":"131.241431ms","start":"2026-08-22T10:42:10.466739Z","end":"2026-08-22T10:42:10.59798Z","steps":["trace[397225164] 'agreement among raft nodes before linearized reading' (duration: 130.817724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:10.836662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.002837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-22T10:42:10.836788Z","caller":"traceutil/trace.go:171","msg":"trace[1638209357] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5084; }","duration":"133.20104ms","start":"2026-08-22T10:42:10.70356Z","end":"2026-08-22T10:42:10.836761Z","steps":["trace[1638209357] 'range keys from in-memory index tree' (duration: 132.727853ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:47.031398Z","caller":"traceutil/trace.go:171","msg":"trace[1518412545] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"180.462297ms","start":"2026-08-22T10:42:46.850904Z","end":"2026-08-22T10:42:47.031366Z","steps":["trace[1518412545] 'process raft request' (duration: 178.554399ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:47.031749Z","caller":"traceutil/trace.go:171","msg":"trace[815960982] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"176.670133ms","start":"2026-08-22T10:42:46.855048Z","end":"2026-08-22T10:42:47.031718Z","steps":["trace[815960982] 'process raft request' (duration: 176.499169ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:50.743204Z","caller":"traceutil/trace.go:171","msg":"trace[328612874] transaction","detail":"{read_only:false; response_revision:5406; number_of_response:1; }","duration":"101.634402ms","start":"2026-08-22T10:42:50.641547Z","end":"2026-08-22T10:42:50.743182Z","steps":["trace[328612874] 'process raft request' (duration: 101.47641ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:51.00671Z","caller":"traceutil/trace.go:171","msg":"trace[373130273] transaction","detail":"{read_only:false; response_revision:5407; number_of_response:1; }","duration":"363.960557ms","start":"2026-08-22T10:42:50.642694Z","end":"2026-08-22T10:42:51.006654Z","steps":["trace[373130273] 'process raft request' (duration: 328.885576ms)","trace[373130273] 'compare' (duration: 34.895729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:51.006875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:50.642664Z","time spent":"364.127121ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:51.014613Z","caller":"traceutil/trace.go:171","msg":"trace[1408033490] transaction","detail":"{read_only:false; response_revision:5408; number_of_response:1; }","duration":"369.975906ms","start":"2026-08-22T10:42:50.644606Z","end":"2026-08-22T10:42:51.014582Z","steps":["trace[1408033490] 'process raft request' (duration: 361.987719ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:51.014852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:50.644589Z","time spent":"370.215539ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:51.015348Z","caller":"traceutil/trace.go:171","msg":"trace[1660161680] linearizableReadLoop","detail":"{readStateIndex:5612; appliedIndex:5609; }","duration":"150.431715ms","start":"2026-08-22T10:42:50.864908Z","end":"2026-08-22T10:42:51.01534Z","steps":["trace[1660161680] 'read index received' (duration: 106.604705ms)","trace[1660161680] 'applied index is now lower than readState.Index' (duration: 43.82647ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:42:51.015421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.520356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-22T10:42:51.015442Z","caller":"traceutil/trace.go:171","msg":"trace[1153283308] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:5409; }","duration":"150.573116ms","start":"2026-08-22T10:42:50.864863Z","end":"2026-08-22T10:42:51.015436Z","steps":["trace[1153283308] 'agreement among raft nodes before linearized reading' (duration: 150.512555ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:51.015467Z","caller":"traceutil/trace.go:171","msg":"trace[1948104490] transaction","detail":"{read_only:false; response_revision:5409; number_of_response:1; }","duration":"324.97532ms","start":"2026-08-22T10:42:50.690457Z","end":"2026-08-22T10:42:51.015432Z","steps":["trace[1948104490] 'process raft request' (duration: 323.979875ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:42:51.015533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:42:50.690439Z","time spent":"325.068751ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:42:51.581663Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2822} {"level":"warn","ts":"2026-08-22T10:42:51.797799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.622171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:42:51.797944Z","caller":"traceutil/trace.go:171","msg":"trace[561868445] transaction","detail":"{read_only:false; response_revision:5417; number_of_response:1; }","duration":"175.483908ms","start":"2026-08-22T10:42:51.622438Z","end":"2026-08-22T10:42:51.797922Z","steps":["trace[561868445] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/openstack/keystone-credential-setup-2r2ks.18ce1a9ed69967f5; req_size:772; } (duration: 173.186895ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:42:51.905668Z","caller":"traceutil/trace.go:171","msg":"trace[43378507] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"102.207999ms","start":"2026-08-22T10:42:51.803099Z","end":"2026-08-22T10:42:51.905307Z","steps":["trace[43378507] 'process raft request' (duration: 20.827383ms)","trace[43378507] 'compare' (duration: 16.580132ms)","trace[43378507] 'marshal mvccpb.KeyValue' {req_type:put; key:/registry/events/openstack/keystone-credential-setup-2r2ks.18ce1a9edbf2c600; req_size:772; } (duration: 64.624932ms)"],"step_count":3} {"level":"info","ts":"2026-08-22T10:42:51.944046Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2822,"took":"356.428956ms","hash":1309025107,"current-db-size-bytes":51785728,"current-db-size":"52 MB","current-db-size-in-use-bytes":28102656,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-22T10:42:51.944123Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1309025107,"revision":2822,"compact-revision":-1} {"level":"info","ts":"2026-08-22T10:43:03.472678Z","caller":"traceutil/trace.go:171","msg":"trace[847482699] transaction","detail":"{read_only:false; response_revision:5506; number_of_response:1; }","duration":"141.618752ms","start":"2026-08-22T10:43:03.331027Z","end":"2026-08-22T10:43:03.472646Z","steps":["trace[847482699] 'process raft request' (duration: 77.885059ms)","trace[847482699] 'compare' (duration: 63.228166ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:12.654379Z","caller":"traceutil/trace.go:171","msg":"trace[1439759394] transaction","detail":"{read_only:false; response_revision:5560; number_of_response:1; }","duration":"107.062215ms","start":"2026-08-22T10:43:12.547271Z","end":"2026-08-22T10:43:12.654333Z","steps":["trace[1439759394] 'process raft request' (duration: 79.480787ms)","trace[1439759394] 'compare' (duration: 27.360985ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:12.853001Z","caller":"traceutil/trace.go:171","msg":"trace[1610302191] transaction","detail":"{read_only:false; response_revision:5561; number_of_response:1; }","duration":"184.218789ms","start":"2026-08-22T10:43:12.668751Z","end":"2026-08-22T10:43:12.852969Z","steps":["trace[1610302191] 'process raft request' (duration: 151.909303ms)","trace[1610302191] 'compare' (duration: 32.132074ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:13.104808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.395902ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:43:13.104994Z","caller":"traceutil/trace.go:171","msg":"trace[908757969] transaction","detail":"{read_only:false; response_revision:5563; number_of_response:1; }","duration":"198.501435ms","start":"2026-08-22T10:43:12.90646Z","end":"2026-08-22T10:43:13.104962Z","steps":["trace[908757969] 'process raft request' (duration: 76.863299ms)","trace[908757969] 'compare' (duration: 120.472ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:13.137883Z","caller":"traceutil/trace.go:171","msg":"trace[2129854454] transaction","detail":"{read_only:false; response_revision:5564; number_of_response:1; }","duration":"209.190998ms","start":"2026-08-22T10:43:12.928638Z","end":"2026-08-22T10:43:13.137829Z","steps":["trace[2129854454] 'process raft request' (duration: 208.978055ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:13.256854Z","caller":"traceutil/trace.go:171","msg":"trace[755993950] transaction","detail":"{read_only:false; response_revision:5568; number_of_response:1; }","duration":"108.339314ms","start":"2026-08-22T10:43:13.148493Z","end":"2026-08-22T10:43:13.256833Z","steps":["trace[755993950] 'process raft request' (duration: 108.07457ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:13.256919Z","caller":"traceutil/trace.go:171","msg":"trace[1978350104] transaction","detail":"{read_only:false; response_revision:5567; number_of_response:1; }","duration":"113.758221ms","start":"2026-08-22T10:43:13.143132Z","end":"2026-08-22T10:43:13.256891Z","steps":["trace[1978350104] 'process raft request' (duration: 111.144573ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:25.689395Z","caller":"traceutil/trace.go:171","msg":"trace[390775406] transaction","detail":"{read_only:false; response_revision:5675; number_of_response:1; }","duration":"115.963564ms","start":"2026-08-22T10:43:25.573409Z","end":"2026-08-22T10:43:25.689373Z","steps":["trace[390775406] 'process raft request' (duration: 115.818782ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:25.82358Z","caller":"traceutil/trace.go:171","msg":"trace[801128184] linearizableReadLoop","detail":"{readStateIndex:5888; appliedIndex:5886; }","duration":"143.267366ms","start":"2026-08-22T10:43:25.680286Z","end":"2026-08-22T10:43:25.823554Z","steps":["trace[801128184] 'read index received' (duration: 9.015789ms)","trace[801128184] 'applied index is now lower than readState.Index' (duration: 134.250287ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:43:25.823719Z","caller":"traceutil/trace.go:171","msg":"trace[305701427] transaction","detail":"{read_only:false; response_revision:5676; number_of_response:1; }","duration":"235.050504ms","start":"2026-08-22T10:43:25.588625Z","end":"2026-08-22T10:43:25.823676Z","steps":["trace[305701427] 'process raft request' (duration: 182.143375ms)","trace[305701427] 'compare' (duration: 52.676366ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:43:25.82381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.51588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:1 size:8317"} {"level":"info","ts":"2026-08-22T10:43:25.823852Z","caller":"traceutil/trace.go:171","msg":"trace[1060051109] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:1; response_revision:5676; }","duration":"143.608502ms","start":"2026-08-22T10:43:25.680234Z","end":"2026-08-22T10:43:25.823842Z","steps":["trace[1060051109] 'agreement among raft nodes before linearized reading' (duration: 143.46397ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:25.82404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.77088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T10:43:25.824092Z","caller":"traceutil/trace.go:171","msg":"trace[1549693895] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5676; }","duration":"126.874381ms","start":"2026-08-22T10:43:25.697204Z","end":"2026-08-22T10:43:25.824079Z","steps":["trace[1549693895] 'agreement among raft nodes before linearized reading' (duration: 126.705428ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:43:25.8241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.50391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-22T10:43:25.824199Z","caller":"traceutil/trace.go:171","msg":"trace[1792013563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5676; }","duration":"127.623882ms","start":"2026-08-22T10:43:25.696517Z","end":"2026-08-22T10:43:25.824141Z","steps":["trace[1792013563] 'agreement among raft nodes before linearized reading' (duration: 127.200276ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:43:31.405676Z","caller":"traceutil/trace.go:171","msg":"trace[597769187] transaction","detail":"{read_only:false; response_revision:5706; number_of_response:1; }","duration":"101.057527ms","start":"2026-08-22T10:43:31.304594Z","end":"2026-08-22T10:43:31.405651Z","steps":["trace[597769187] 'process raft request' (duration: 100.913095ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:26.039648Z","caller":"traceutil/trace.go:171","msg":"trace[1552723191] linearizableReadLoop","detail":"{readStateIndex:6259; appliedIndex:6258; }","duration":"113.195604ms","start":"2026-08-22T10:44:25.92643Z","end":"2026-08-22T10:44:26.039626Z","steps":["trace[1552723191] 'read index received' (duration: 85.93302ms)","trace[1552723191] 'applied index is now lower than readState.Index' (duration: 27.261654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:44:26.039877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.422358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T10:44:26.039915Z","caller":"traceutil/trace.go:171","msg":"trace[2132590815] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6035; }","duration":"113.503609ms","start":"2026-08-22T10:44:25.926403Z","end":"2026-08-22T10:44:26.039907Z","steps":["trace[2132590815] 'agreement among raft nodes before linearized reading' (duration: 113.316466ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:44:26.040133Z","caller":"traceutil/trace.go:171","msg":"trace[1668313447] transaction","detail":"{read_only:false; response_revision:6035; number_of_response:1; }","duration":"127.520026ms","start":"2026-08-22T10:44:25.912575Z","end":"2026-08-22T10:44:26.040095Z","steps":["trace[1668313447] 'process raft request' (duration: 99.803226ms)","trace[1668313447] 'compare' (duration: 26.987139ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:25.052776Z","caller":"traceutil/trace.go:171","msg":"trace[894451726] transaction","detail":"{read_only:false; response_revision:6389; number_of_response:1; }","duration":"119.366424ms","start":"2026-08-22T10:45:24.93338Z","end":"2026-08-22T10:45:25.052747Z","steps":["trace[894451726] 'process raft request' (duration: 117.518458ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:25.075005Z","caller":"traceutil/trace.go:171","msg":"trace[80051107] transaction","detail":"{read_only:false; response_revision:6390; number_of_response:1; }","duration":"137.561959ms","start":"2026-08-22T10:45:24.93741Z","end":"2026-08-22T10:45:25.074972Z","steps":["trace[80051107] 'process raft request' (duration: 137.376164ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:41.738998Z","caller":"traceutil/trace.go:171","msg":"trace[376410091] linearizableReadLoop","detail":"{readStateIndex:6832; appliedIndex:6831; }","duration":"234.801666ms","start":"2026-08-22T10:45:41.504139Z","end":"2026-08-22T10:45:41.73894Z","steps":["trace[376410091] 'read index received' (duration: 234.252111ms)","trace[376410091] 'applied index is now lower than readState.Index' (duration: 548.235µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:41.73914Z","caller":"traceutil/trace.go:171","msg":"trace[679852886] transaction","detail":"{read_only:false; response_revision:6588; number_of_response:1; }","duration":"244.534262ms","start":"2026-08-22T10:45:41.49454Z","end":"2026-08-22T10:45:41.739075Z","steps":["trace[679852886] 'process raft request' (duration: 243.947974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:45:41.739238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.054645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:45:41.739288Z","caller":"traceutil/trace.go:171","msg":"trace[971200644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6588; }","duration":"235.178388ms","start":"2026-08-22T10:45:41.504096Z","end":"2026-08-22T10:45:41.739275Z","steps":["trace[971200644] 'agreement among raft nodes before linearized reading' (duration: 235.040824ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:52.165174Z","caller":"traceutil/trace.go:171","msg":"trace[307462921] transaction","detail":"{read_only:false; response_revision:6663; number_of_response:1; }","duration":"114.017435ms","start":"2026-08-22T10:45:52.051125Z","end":"2026-08-22T10:45:52.165143Z","steps":["trace[307462921] 'process raft request' (duration: 46.764969ms)","trace[307462921] 'compare' (duration: 67.135262ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:52.331841Z","caller":"traceutil/trace.go:171","msg":"trace[276424984] transaction","detail":"{read_only:false; response_revision:6664; number_of_response:1; }","duration":"209.684676ms","start":"2026-08-22T10:45:52.122133Z","end":"2026-08-22T10:45:52.331818Z","steps":["trace[276424984] 'process raft request' (duration: 197.93597ms)","trace[276424984] 'compare' (duration: 11.641483ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:52.333794Z","caller":"traceutil/trace.go:171","msg":"trace[993076185] transaction","detail":"{read_only:false; response_revision:6665; number_of_response:1; }","duration":"122.460446ms","start":"2026-08-22T10:45:52.211283Z","end":"2026-08-22T10:45:52.333743Z","steps":["trace[993076185] 'process raft request' (duration: 122.199019ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:45:58.488198Z","caller":"traceutil/trace.go:171","msg":"trace[164748262] transaction","detail":"{read_only:false; response_revision:6700; number_of_response:1; }","duration":"117.12652ms","start":"2026-08-22T10:45:58.371034Z","end":"2026-08-22T10:45:58.488161Z","steps":["trace[164748262] 'process raft request' (duration: 91.274107ms)","trace[164748262] 'compare' (duration: 25.314868ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:58.489127Z","caller":"traceutil/trace.go:171","msg":"trace[116457944] linearizableReadLoop","detail":"{readStateIndex:6949; appliedIndex:6947; }","duration":"107.60832ms","start":"2026-08-22T10:45:58.381502Z","end":"2026-08-22T10:45:58.489111Z","steps":["trace[116457944] 'read index received' (duration: 80.708677ms)","trace[116457944] 'applied index is now lower than readState.Index' (duration: 26.898463ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:45:58.489557Z","caller":"traceutil/trace.go:171","msg":"trace[796606949] transaction","detail":"{read_only:false; response_revision:6701; number_of_response:1; }","duration":"114.859166ms","start":"2026-08-22T10:45:58.374668Z","end":"2026-08-22T10:45:58.489527Z","steps":["trace[796606949] 'process raft request' (duration: 113.429255ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:45:58.489602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.066513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/identity.18ce1ac284a29ade\" ","response":"range_response_count:1 size:670"} {"level":"info","ts":"2026-08-22T10:45:58.489684Z","caller":"traceutil/trace.go:171","msg":"trace[2012955941] range","detail":"{range_begin:/registry/events/openstack/identity.18ce1ac284a29ade; range_end:; response_count:1; response_revision:6701; }","duration":"108.177017ms","start":"2026-08-22T10:45:58.381484Z","end":"2026-08-22T10:45:58.489661Z","steps":["trace[2012955941] 'agreement among raft nodes before linearized reading' (duration: 107.818427ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:15.670342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.358403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:15.670441Z","caller":"traceutil/trace.go:171","msg":"trace[762660152] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6845; }","duration":"165.521018ms","start":"2026-08-22T10:46:15.504898Z","end":"2026-08-22T10:46:15.670419Z","steps":["trace[762660152] 'range keys from in-memory index tree' (duration: 165.246871ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:16.829622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.659042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:46:16.829716Z","caller":"traceutil/trace.go:171","msg":"trace[1763571576] linearizableReadLoop","detail":"{readStateIndex:7102; appliedIndex:7101; }","duration":"320.280757ms","start":"2026-08-22T10:46:16.509418Z","end":"2026-08-22T10:46:16.829699Z","steps":["trace[1763571576] 'read index received' (duration: 17.244744ms)","trace[1763571576] 'applied index is now lower than readState.Index' (duration: 303.034963ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:16.8299Z","caller":"traceutil/trace.go:171","msg":"trace[305551223] transaction","detail":"{read_only:false; response_revision:6847; number_of_response:1; }","duration":"406.2684ms","start":"2026-08-22T10:46:16.423621Z","end":"2026-08-22T10:46:16.829889Z","steps":["trace[305551223] 'process raft request' (duration: 103.246019ms)","trace[305551223] 'compare' (duration: 302.464136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:16.829951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.423584Z","time spent":"406.344482ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:46:16.831199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.727916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:16.8317Z","caller":"traceutil/trace.go:171","msg":"trace[317210667] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6847; }","duration":"322.344072ms","start":"2026-08-22T10:46:16.509328Z","end":"2026-08-22T10:46:16.831672Z","steps":["trace[317210667] 'agreement among raft nodes before linearized reading' (duration: 321.501259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:16.83179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.5093Z","time spent":"322.479206ms","remote":"127.0.0.1:59740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T10:46:17.142375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.705132ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T10:46:17.142571Z","caller":"traceutil/trace.go:171","msg":"trace[232387728] linearizableReadLoop","detail":"{readStateIndex:7104; appliedIndex:7102; }","duration":"312.815244ms","start":"2026-08-22T10:46:16.829738Z","end":"2026-08-22T10:46:17.142553Z","steps":["trace[232387728] 'read index received' (duration: 151.875565ms)","trace[232387728] 'applied index is now lower than readState.Index' (duration: 160.938269ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:17.14297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.270717ms","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-08-22T10:46:17.143083Z","caller":"traceutil/trace.go:171","msg":"trace[956980039] transaction","detail":"{read_only:false; response_revision:6849; number_of_response:1; }","duration":"539.864428ms","start":"2026-08-22T10:46:16.603195Z","end":"2026-08-22T10:46:17.14306Z","steps":["trace[956980039] 'process raft request' (duration: 539.306411ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.143222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.603157Z","time spent":"539.992191ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T10:46:17.143335Z","caller":"traceutil/trace.go:171","msg":"trace[1671082703] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6849; }","duration":"373.459423ms","start":"2026-08-22T10:46:16.769634Z","end":"2026-08-22T10:46:17.143094Z","steps":["trace[1671082703] 'agreement among raft nodes before linearized reading' (duration: 373.146804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.143432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.060911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-22T10:46:17.143436Z","caller":"traceutil/trace.go:171","msg":"trace[2053234499] transaction","detail":"{read_only:false; response_revision:6848; number_of_response:1; }","duration":"540.530746ms","start":"2026-08-22T10:46:16.602836Z","end":"2026-08-22T10:46:17.143366Z","steps":["trace[2053234499] 'process raft request' (duration: 378.732403ms)","trace[2053234499] 'compare' (duration: 160.549808ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:17.143449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.769617Z","time spent":"373.816173ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-22T10:46:17.143598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.602809Z","time spent":"540.68413ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-22T10:46:17.143659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.997019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-22T10:46:17.143464Z","caller":"traceutil/trace.go:171","msg":"trace[2031054116] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6849; }","duration":"369.106282ms","start":"2026-08-22T10:46:16.774351Z","end":"2026-08-22T10:46:17.143457Z","steps":["trace[2031054116] 'agreement among raft nodes before linearized reading' (duration: 368.975449ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:17.143832Z","caller":"traceutil/trace.go:171","msg":"trace[1388959938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:6849; }","duration":"437.230075ms","start":"2026-08-22T10:46:16.706548Z","end":"2026-08-22T10:46:17.143779Z","steps":["trace[1388959938] 'agreement among raft nodes before linearized reading' (duration: 436.558157ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.143885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.774344Z","time spent":"369.479193ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T10:46:17.143924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.706522Z","time spent":"437.358329ms","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-22T10:46:17.146236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.142856ms","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-08-22T10:46:17.146356Z","caller":"traceutil/trace.go:171","msg":"trace[935110575] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:6849; }","duration":"101.263319ms","start":"2026-08-22T10:46:17.045077Z","end":"2026-08-22T10:46:17.146341Z","steps":["trace[935110575] 'agreement among raft nodes before linearized reading' (duration: 101.037623ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.147188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.994212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-22T10:46:17.147311Z","caller":"traceutil/trace.go:171","msg":"trace[1750336338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6849; }","duration":"180.153607ms","start":"2026-08-22T10:46:16.967144Z","end":"2026-08-22T10:46:17.147297Z","steps":["trace[1750336338] 'agreement among raft nodes before linearized reading' (duration: 179.966372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.144747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.006942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:17.149314Z","caller":"traceutil/trace.go:171","msg":"trace[1905962249] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6849; }","duration":"316.594368ms","start":"2026-08-22T10:46:16.832702Z","end":"2026-08-22T10:46:17.149297Z","steps":["trace[1905962249] 'agreement among raft nodes before linearized reading' (duration: 312.004052ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:17.149504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T10:46:16.832686Z","time spent":"316.794343ms","remote":"127.0.0.1:59728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-22T10:46:17.279184Z","caller":"traceutil/trace.go:171","msg":"trace[1596260582] transaction","detail":"{read_only:false; response_revision:6852; number_of_response:1; }","duration":"127.552321ms","start":"2026-08-22T10:46:17.151612Z","end":"2026-08-22T10:46:17.279165Z","steps":["trace[1596260582] 'process raft request' (duration: 127.494259ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:17.279305Z","caller":"traceutil/trace.go:171","msg":"trace[1432717042] transaction","detail":"{read_only:false; response_revision:6851; number_of_response:1; }","duration":"129.240678ms","start":"2026-08-22T10:46:17.149993Z","end":"2026-08-22T10:46:17.279233Z","steps":["trace[1432717042] 'process raft request' (duration: 96.070867ms)","trace[1432717042] 'compare' (duration: 32.918604ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:17.279404Z","caller":"traceutil/trace.go:171","msg":"trace[1414004938] transaction","detail":"{read_only:false; response_revision:6853; number_of_response:1; }","duration":"127.688254ms","start":"2026-08-22T10:46:17.151711Z","end":"2026-08-22T10:46:17.279399Z","steps":["trace[1414004938] 'process raft request' (duration: 127.427267ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:17.280196Z","caller":"traceutil/trace.go:171","msg":"trace[2059638263] transaction","detail":"{read_only:false; response_revision:6854; number_of_response:1; }","duration":"128.143206ms","start":"2026-08-22T10:46:17.152041Z","end":"2026-08-22T10:46:17.280184Z","steps":["trace[2059638263] 'process raft request' (duration: 127.332014ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:27.0965Z","caller":"traceutil/trace.go:171","msg":"trace[2133101510] transaction","detail":"{read_only:false; response_revision:6898; number_of_response:1; }","duration":"196.657426ms","start":"2026-08-22T10:46:26.89982Z","end":"2026-08-22T10:46:27.096478Z","steps":["trace[2133101510] 'process raft request' (duration: 196.226633ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:27.345205Z","caller":"traceutil/trace.go:171","msg":"trace[1468257632] linearizableReadLoop","detail":"{readStateIndex:7157; appliedIndex:7156; }","duration":"109.000853ms","start":"2026-08-22T10:46:27.236191Z","end":"2026-08-22T10:46:27.345192Z","steps":["trace[1468257632] 'read index received' (duration: 101.212064ms)","trace[1468257632] 'applied index is now lower than readState.Index' (duration: 7.788099ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:27.345286Z","caller":"traceutil/trace.go:171","msg":"trace[34447341] transaction","detail":"{read_only:false; response_revision:6900; number_of_response:1; }","duration":"142.858728ms","start":"2026-08-22T10:46:27.202421Z","end":"2026-08-22T10:46:27.34528Z","steps":["trace[34447341] 'process raft request' (duration: 134.999066ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T10:46:27.345413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.212959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-22T10:46:27.345433Z","caller":"traceutil/trace.go:171","msg":"trace[1475901409] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6900; }","duration":"109.27306ms","start":"2026-08-22T10:46:27.236155Z","end":"2026-08-22T10:46:27.345428Z","steps":["trace[1475901409] 'agreement among raft nodes before linearized reading' (duration: 109.193518ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:27.556528Z","caller":"traceutil/trace.go:171","msg":"trace[667787975] transaction","detail":"{read_only:false; response_revision:6902; number_of_response:1; }","duration":"204.817491ms","start":"2026-08-22T10:46:27.351698Z","end":"2026-08-22T10:46:27.556515Z","steps":["trace[667787975] 'process raft request' (duration: 193.008232ms)","trace[667787975] 'compare' (duration: 11.264274ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:27.557122Z","caller":"traceutil/trace.go:171","msg":"trace[485506655] transaction","detail":"{read_only:false; response_revision:6905; number_of_response:1; }","duration":"201.502441ms","start":"2026-08-22T10:46:27.355609Z","end":"2026-08-22T10:46:27.557112Z","steps":["trace[485506655] 'process raft request' (duration: 200.726411ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:27.557143Z","caller":"traceutil/trace.go:171","msg":"trace[1742483396] transaction","detail":"{read_only:false; response_revision:6903; number_of_response:1; }","duration":"205.373696ms","start":"2026-08-22T10:46:27.351765Z","end":"2026-08-22T10:46:27.557139Z","steps":["trace[1742483396] 'process raft request' (duration: 204.511093ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:27.557163Z","caller":"traceutil/trace.go:171","msg":"trace[1919495362] transaction","detail":"{read_only:false; response_revision:6904; number_of_response:1; }","duration":"204.918114ms","start":"2026-08-22T10:46:27.35224Z","end":"2026-08-22T10:46:27.557158Z","steps":["trace[1919495362] 'process raft request' (duration: 204.075191ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:29.628313Z","caller":"traceutil/trace.go:171","msg":"trace[1319550501] transaction","detail":"{read_only:false; response_revision:6910; number_of_response:1; }","duration":"197.202247ms","start":"2026-08-22T10:46:29.431084Z","end":"2026-08-22T10:46:29.628287Z","steps":["trace[1319550501] 'process raft request' (duration: 116.977849ms)","trace[1319550501] 'compare' (duration: 79.957711ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T10:46:29.629128Z","caller":"traceutil/trace.go:171","msg":"trace[1810239421] linearizableReadLoop","detail":"{readStateIndex:7167; appliedIndex:7166; }","duration":"124.203184ms","start":"2026-08-22T10:46:29.504871Z","end":"2026-08-22T10:46:29.629074Z","steps":["trace[1810239421] 'read index received' (duration: 43.204104ms)","trace[1810239421] 'applied index is now lower than readState.Index' (duration: 80.99584ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T10:46:29.629242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.346618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T10:46:29.629305Z","caller":"traceutil/trace.go:171","msg":"trace[335655469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:6910; }","duration":"124.441ms","start":"2026-08-22T10:46:29.504849Z","end":"2026-08-22T10:46:29.62929Z","steps":["trace[335655469] 'agreement among raft nodes before linearized reading' (duration: 124.292226ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:46:29.63174Z","caller":"traceutil/trace.go:171","msg":"trace[1901580760] transaction","detail":"{read_only:false; response_revision:6911; number_of_response:1; }","duration":"105.589853ms","start":"2026-08-22T10:46:29.526136Z","end":"2026-08-22T10:46:29.631726Z","steps":["trace[1901580760] 'process raft request' (duration: 102.964112ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T10:47:51.589155Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5416} {"level":"info","ts":"2026-08-22T10:47:51.716851Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5416,"took":"123.884819ms","hash":200884284,"current-db-size-bytes":51785728,"current-db-size":"52 MB","current-db-size-in-use-bytes":18395136,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-22T10:47:51.716929Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":200884284,"revision":5416,"compact-revision":2822}