{"level":"warn","ts":"2026-08-28T04:03:57.34963Z","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-28T04:03:57.349715Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.119: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://162.253.55.119:2380","--initial-cluster=instance=https://162.253.55.119:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.119:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.119: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-28T04:03:57.349776Z","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-28T04:03:57.349801Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.119:2380"]} {"level":"info","ts":"2026-08-28T04:03:57.349818Z","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-28T04:03:57.350366Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"]} {"level":"info","ts":"2026-08-28T04:03:57.35043Z","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://162.253.55.119:2380"],"listen-peer-urls":["https://162.253.55.119:2380"],"advertise-client-urls":["https://162.253.55.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.119: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-28T04:03:57.354298Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.679865ms"} {"level":"info","ts":"2026-08-28T04:03:57.425856Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2f0e00eb3c3ee1b","cluster-id":"8e467f099f65c603"} {"level":"info","ts":"2026-08-28T04:03:57.42596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T04:03:57.425983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became follower at term 0"} {"level":"info","ts":"2026-08-28T04:03:57.425993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2f0e00eb3c3ee1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T04:03:57.426007Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became follower at term 1"} {"level":"info","ts":"2026-08-28T04:03:57.426045Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=(211915536236539419)"} {"level":"warn","ts":"2026-08-28T04:03:57.734565Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T04:03:57.87893Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T04:03:57.998306Z","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-28T04:03:58.060716Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2f0e00eb3c3ee1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T04:03:58.061266Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2f0e00eb3c3ee1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T04:03:58.061273Z","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-28T04:03:58.06197Z","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-28T04:03:58.062457Z","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-28T04:03:58.065948Z","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-28T04:03:58.066274Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.119:2380"} {"level":"info","ts":"2026-08-28T04:03:58.066351Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.119:2380"} {"level":"info","ts":"2026-08-28T04:03:58.066357Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2f0e00eb3c3ee1b","initial-advertise-peer-urls":["https://162.253.55.119:2380"],"listen-peer-urls":["https://162.253.55.119:2380"],"advertise-client-urls":["https://162.253.55.119:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.119:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T04:03:58.066409Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T04:03:58.083702Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b switched to configuration voters=(211915536236539419)"} {"level":"info","ts":"2026-08-28T04:03:58.08382Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8e467f099f65c603","local-member-id":"2f0e00eb3c3ee1b","added-peer-id":"2f0e00eb3c3ee1b","added-peer-peer-urls":["https://162.253.55.119:2380"]} {"level":"info","ts":"2026-08-28T04:03:58.227075Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T04:03:58.227175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T04:03:58.227403Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b received MsgPreVoteResp from 2f0e00eb3c3ee1b at term 1"} {"level":"info","ts":"2026-08-28T04:03:58.227448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became candidate at term 2"} {"level":"info","ts":"2026-08-28T04:03:58.22747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b received MsgVoteResp from 2f0e00eb3c3ee1b at term 2"} {"level":"info","ts":"2026-08-28T04:03:58.227539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2f0e00eb3c3ee1b became leader at term 2"} {"level":"info","ts":"2026-08-28T04:03:58.22757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2f0e00eb3c3ee1b elected leader 2f0e00eb3c3ee1b at term 2"} {"level":"info","ts":"2026-08-28T04:03:58.291712Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2f0e00eb3c3ee1b","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.119:2379]}","request-path":"/0/members/2f0e00eb3c3ee1b/attributes","cluster-id":"8e467f099f65c603","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T04:03:58.291726Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:03:58.291854Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:03:58.291826Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:03:58.292153Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T04:03:58.292206Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T04:03:58.293879Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-28T04:03:58.294066Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.119:2379"} {"level":"info","ts":"2026-08-28T04:03:58.311085Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8e467f099f65c603","local-member-id":"2f0e00eb3c3ee1b","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:03:58.311255Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:03:58.311287Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:03:59.726093Z","caller":"traceutil/trace.go:171","msg":"trace[1599354546] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"116.161633ms","start":"2026-08-28T04:03:59.609902Z","end":"2026-08-28T04:03:59.726064Z","steps":["trace[1599354546] 'process raft request' (duration: 115.965641ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:03:59.726296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.314053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:03:59.726395Z","caller":"traceutil/trace.go:171","msg":"trace[1184764241] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:9; }","duration":"115.452115ms","start":"2026-08-28T04:03:59.61092Z","end":"2026-08-28T04:03:59.726372Z","steps":["trace[1184764241] 'agreement among raft nodes before linearized reading' (duration: 115.244432ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:03:59.726414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.633967ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:03:59.72652Z","caller":"traceutil/trace.go:171","msg":"trace[12047567] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:0; response_revision:9; }","duration":"115.89156ms","start":"2026-08-28T04:03:59.610609Z","end":"2026-08-28T04:03:59.726501Z","steps":["trace[12047567] 'agreement among raft nodes before linearized reading' (duration: 115.562166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:03:59.726566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.966944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:03:59.726694Z","caller":"traceutil/trace.go:171","msg":"trace[97991053] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:9; }","duration":"175.158006ms","start":"2026-08-28T04:03:59.551516Z","end":"2026-08-28T04:03:59.726674Z","steps":["trace[97991053] 'agreement among raft nodes before linearized reading' (duration: 174.914443ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:03:59.726761Z","caller":"traceutil/trace.go:171","msg":"trace[644906960] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"116.69088ms","start":"2026-08-28T04:03:59.610047Z","end":"2026-08-28T04:03:59.726737Z","steps":["trace[644906960] 'process raft request' (duration: 115.88083ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:03:59.726755Z","caller":"traceutil/trace.go:171","msg":"trace[1517568708] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"117.126475ms","start":"2026-08-28T04:03:59.609594Z","end":"2026-08-28T04:03:59.726721Z","steps":["trace[1517568708] 'process raft request' (duration: 107.50588ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:03:59.726825Z","caller":"traceutil/trace.go:171","msg":"trace[846512697] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"116.814221ms","start":"2026-08-28T04:03:59.610004Z","end":"2026-08-28T04:03:59.726818Z","steps":["trace[846512697] 'process raft request' (duration: 115.89568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:03:59.726641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.98198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:03:59.726929Z","caller":"traceutil/trace.go:171","msg":"trace[1957386364] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:9; }","duration":"115.296003ms","start":"2026-08-28T04:03:59.611626Z","end":"2026-08-28T04:03:59.726922Z","steps":["trace[1957386364] 'agreement among raft nodes before linearized reading' (duration: 114.99628ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:03:59.726098Z","caller":"traceutil/trace.go:171","msg":"trace[1909471429] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"115.196542ms","start":"2026-08-28T04:03:59.610885Z","end":"2026-08-28T04:03:59.726082Z","steps":["trace[1909471429] 'process raft request' (duration: 115.092271ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:03:59.726029Z","caller":"traceutil/trace.go:171","msg":"trace[617026834] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"116.265344ms","start":"2026-08-28T04:03:59.609734Z","end":"2026-08-28T04:03:59.725999Z","steps":["trace[617026834] 'process raft request' (duration: 116.090152ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.181195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.223449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:00.181505Z","caller":"traceutil/trace.go:171","msg":"trace[1977231860] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:12; }","duration":"455.455993ms","start":"2026-08-28T04:03:59.726028Z","end":"2026-08-28T04:04:00.181484Z","steps":["trace[1977231860] 'read index received' (duration: 151.500845ms)","trace[1977231860] 'applied index is now lower than readState.Index' (duration: 303.953168ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:00.181621Z","caller":"traceutil/trace.go:171","msg":"trace[312398614] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"547.708221ms","start":"2026-08-28T04:03:59.633861Z","end":"2026-08-28T04:04:00.181569Z","steps":["trace[312398614] 'process raft request' (duration: 243.571931ms)","trace[312398614] 'compare' (duration: 303.082858ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:00.181693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.190488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.181716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.320631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.181729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.848171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.181782Z","caller":"traceutil/trace.go:171","msg":"trace[138317013] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"491.295539ms","start":"2026-08-28T04:03:59.690458Z","end":"2026-08-28T04:04:00.181754Z","steps":["trace[138317013] 'agreement among raft nodes before linearized reading' (duration: 491.070677ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.181759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.633836Z","time spent":"547.842913ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.181788Z","caller":"traceutil/trace.go:171","msg":"trace[1169510088] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:11; }","duration":"518.421282ms","start":"2026-08-28T04:03:59.663344Z","end":"2026-08-28T04:04:00.181765Z","steps":["trace[1169510088] 'agreement among raft nodes before linearized reading' (duration: 518.23002ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.181829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.690442Z","time spent":"491.37423ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-28T04:04:00.181838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.663328Z","time spent":"518.499493ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"info","ts":"2026-08-28T04:04:00.181838Z","caller":"traceutil/trace.go:171","msg":"trace[787549879] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"520.987773ms","start":"2026-08-28T04:03:59.660809Z","end":"2026-08-28T04:04:00.181797Z","steps":["trace[787549879] 'agreement among raft nodes before linearized reading' (duration: 520.849261ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.181883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.660789Z","time spent":"521.085044ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-28T04:04:00.182Z","caller":"traceutil/trace.go:171","msg":"trace[1626976708] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"547.166755ms","start":"2026-08-28T04:03:59.634824Z","end":"2026-08-28T04:04:00.18199Z","steps":["trace[1626976708] 'process raft request' (duration: 546.463957ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.182056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.634794Z","time spent":"547.224135ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.23451Z","caller":"traceutil/trace.go:171","msg":"trace[474295820] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"505.35382ms","start":"2026-08-28T04:03:59.729125Z","end":"2026-08-28T04:04:00.234479Z","steps":["trace[474295820] 'process raft request' (duration: 505.189069ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.234587Z","caller":"traceutil/trace.go:171","msg":"trace[1339160699] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"504.55623ms","start":"2026-08-28T04:03:59.729999Z","end":"2026-08-28T04:04:00.234555Z","steps":["trace[1339160699] 'process raft request' (duration: 504.438379ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.234657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.729108Z","time spent":"505.477422ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.234691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.729992Z","time spent":"504.649921ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.234716Z","caller":"traceutil/trace.go:171","msg":"trace[259879350] transaction","detail":"{read_only:false; number_of_response:0; response_revision:14; }","duration":"504.719522ms","start":"2026-08-28T04:03:59.729984Z","end":"2026-08-28T04:04:00.234704Z","steps":["trace[259879350] 'process raft request' (duration: 504.485009ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.234803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.729931Z","time spent":"504.843414ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":27,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.234844Z","caller":"traceutil/trace.go:171","msg":"trace[871796548] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"505.131388ms","start":"2026-08-28T04:03:59.729687Z","end":"2026-08-28T04:04:00.234818Z","steps":["trace[871796548] 'process raft request' (duration: 504.712033ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.23491Z","caller":"traceutil/trace.go:171","msg":"trace[211806950] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"504.356848ms","start":"2026-08-28T04:03:59.730545Z","end":"2026-08-28T04:04:00.234902Z","steps":["trace[211806950] 'process raft request' (duration: 503.945544ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.234911Z","caller":"traceutil/trace.go:171","msg":"trace[1665503264] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"503.777791ms","start":"2026-08-28T04:03:59.731121Z","end":"2026-08-28T04:04:00.234898Z","steps":["trace[1665503264] 'process raft request' (duration: 503.524648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.234968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.731113Z","time spent":"503.825852ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.234989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.730531Z","time spent":"504.403719ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.234996Z","caller":"traceutil/trace.go:171","msg":"trace[163377236] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"504.091015ms","start":"2026-08-28T04:03:59.730886Z","end":"2026-08-28T04:04:00.234977Z","steps":["trace[163377236] 'process raft request' (duration: 503.63611ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.234968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.729672Z","time spent":"505.246909ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.235104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.701066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.235104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.730844Z","time spent":"504.202916ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.235154Z","caller":"traceutil/trace.go:171","msg":"trace[259837636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18; }","duration":"506.780567ms","start":"2026-08-28T04:03:59.72836Z","end":"2026-08-28T04:04:00.235141Z","steps":["trace[259837636] 'agreement among raft nodes before linearized reading' (duration: 506.706166ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.235184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.497891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.235198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.728344Z","time spent":"506.844858ms","remote":"127.0.0.1:52102","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-28T04:04:00.23521Z","caller":"traceutil/trace.go:171","msg":"trace[1068214882] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:18; }","duration":"494.558301ms","start":"2026-08-28T04:03:59.740644Z","end":"2026-08-28T04:04:00.235203Z","steps":["trace[1068214882] 'agreement among raft nodes before linearized reading' (duration: 494.258968ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.234829Z","caller":"traceutil/trace.go:171","msg":"trace[2122201675] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"503.808851ms","start":"2026-08-28T04:03:59.731008Z","end":"2026-08-28T04:04:00.234817Z","steps":["trace[2122201675] 'process raft request' (duration: 503.534668ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.235339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.730998Z","time spent":"504.302177ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.235257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:03:59.740638Z","time spent":"494.613222ms","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"info","ts":"2026-08-28T04:04:00.546052Z","caller":"traceutil/trace.go:171","msg":"trace[1172398022] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:22; }","duration":"311.345869ms","start":"2026-08-28T04:04:00.234683Z","end":"2026-08-28T04:04:00.546028Z","steps":["trace[1172398022] 'read index received' (duration: 263.790059ms)","trace[1172398022] 'applied index is now lower than readState.Index' (duration: 47.55471ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:00.546113Z","caller":"traceutil/trace.go:171","msg":"trace[715691676] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"353.701088ms","start":"2026-08-28T04:04:00.192393Z","end":"2026-08-28T04:04:00.546094Z","steps":["trace[715691676] 'process raft request' (duration: 306.005276ms)","trace[715691676] 'compare' (duration: 47.51149ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:00.546245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.031613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-lc2jv\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-08-28T04:04:00.546324Z","caller":"traceutil/trace.go:171","msg":"trace[570268673] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-lc2jv; range_end:; response_count:1; response_revision:19; }","duration":"360.163315ms","start":"2026-08-28T04:04:00.186144Z","end":"2026-08-28T04:04:00.546307Z","steps":["trace[570268673] 'agreement among raft nodes before linearized reading' (duration: 359.999483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.546364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.186123Z","time spent":"360.230365ms","remote":"127.0.0.1:52352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":884,"request content":"key:\"/registry/certificatesigningrequests/csr-lc2jv\" "} {"level":"warn","ts":"2026-08-28T04:04:00.546549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.192371Z","time spent":"353.77855ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:00.637829Z","caller":"traceutil/trace.go:171","msg":"trace[590855836] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"399.681381ms","start":"2026-08-28T04:04:00.23812Z","end":"2026-08-28T04:04:00.637801Z","steps":["trace[590855836] 'process raft request' (duration: 399.392147ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.637931Z","caller":"traceutil/trace.go:171","msg":"trace[1353794762] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"395.936426ms","start":"2026-08-28T04:04:00.241983Z","end":"2026-08-28T04:04:00.63792Z","steps":["trace[1353794762] 'process raft request' (duration: 395.793804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.637958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.086897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-08-28T04:04:00.637982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.024241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.638006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.241966Z","time spent":"395.999117ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.63804Z","caller":"traceutil/trace.go:171","msg":"trace[536556245] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:26; }","duration":"112.105821ms","start":"2026-08-28T04:04:00.525913Z","end":"2026-08-28T04:04:00.638019Z","steps":["trace[536556245] 'agreement among raft nodes before linearized reading' (duration: 111.97447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.945714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.638075Z","caller":"traceutil/trace.go:171","msg":"trace[2022357970] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"395.926535ms","start":"2026-08-28T04:04:00.242109Z","end":"2026-08-28T04:04:00.638036Z","steps":["trace[2022357970] 'process raft request' (duration: 395.699833ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.63817Z","caller":"traceutil/trace.go:171","msg":"trace[685610150] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:26; }","duration":"120.020634ms","start":"2026-08-28T04:04:00.518089Z","end":"2026-08-28T04:04:00.638109Z","steps":["trace[685610150] 'agreement among raft nodes before linearized reading' (duration: 119.954204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.113064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.638255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.922073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.638275Z","caller":"traceutil/trace.go:171","msg":"trace[1762024664] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"396.381671ms","start":"2026-08-28T04:04:00.241882Z","end":"2026-08-28T04:04:00.638263Z","steps":["trace[1762024664] 'process raft request' (duration: 395.862995ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.638291Z","caller":"traceutil/trace.go:171","msg":"trace[1864588353] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:26; }","duration":"130.986713ms","start":"2026-08-28T04:04:00.507295Z","end":"2026-08-28T04:04:00.638282Z","steps":["trace[1864588353] 'agreement among raft nodes before linearized reading' (duration: 130.923683ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.638293Z","caller":"traceutil/trace.go:171","msg":"trace[593561605] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:26; }","duration":"399.186014ms","start":"2026-08-28T04:04:00.239091Z","end":"2026-08-28T04:04:00.638277Z","steps":["trace[593561605] 'agreement among raft nodes before linearized reading' (duration: 399.102633ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.241839Z","time spent":"396.469863ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1007,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.638374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.239084Z","time spent":"399.275765ms","remote":"127.0.0.1:52172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" "} {"level":"info","ts":"2026-08-28T04:04:00.638014Z","caller":"traceutil/trace.go:171","msg":"trace[71683177] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:26; }","duration":"401.184718ms","start":"2026-08-28T04:04:00.236813Z","end":"2026-08-28T04:04:00.637998Z","steps":["trace[71683177] 'agreement among raft nodes before linearized reading' (duration: 401.040406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.236797Z","time spent":"401.712674ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":373,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-08-28T04:04:00.63855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.670145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.637983Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.238107Z","time spent":"399.817562ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.638599Z","caller":"traceutil/trace.go:171","msg":"trace[166039071] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:26; }","duration":"251.718807ms","start":"2026-08-28T04:04:00.386851Z","end":"2026-08-28T04:04:00.63857Z","steps":["trace[166039071] 'agreement among raft nodes before linearized reading' (duration: 251.673006ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.51408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-08-28T04:04:00.637831Z","caller":"traceutil/trace.go:171","msg":"trace[358575869] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"396.578474ms","start":"2026-08-28T04:04:00.241232Z","end":"2026-08-28T04:04:00.637811Z","steps":["trace[358575869] 'process raft request' (duration: 396.394052ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.638681Z","caller":"traceutil/trace.go:171","msg":"trace[1057301587] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:26; }","duration":"400.624291ms","start":"2026-08-28T04:04:00.238042Z","end":"2026-08-28T04:04:00.638667Z","steps":["trace[1057301587] 'agreement among raft nodes before linearized reading' (duration: 400.55475ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.241204Z","time spent":"397.474104ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.638754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.238032Z","time spent":"400.684902ms","remote":"127.0.0.1:52282","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3514,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-28T04:04:00.637831Z","caller":"traceutil/trace.go:171","msg":"trace[496220248] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"396.578344ms","start":"2026-08-28T04:04:00.241232Z","end":"2026-08-28T04:04:00.63781Z","steps":["trace[496220248] 'process raft request' (duration: 396.424832ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.242097Z","time spent":"396.031788ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.638931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.241219Z","time spent":"397.628096ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":956,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.63823Z","caller":"traceutil/trace.go:171","msg":"trace[1035611467] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"396.638785ms","start":"2026-08-28T04:04:00.241575Z","end":"2026-08-28T04:04:00.638214Z","steps":["trace[1035611467] 'process raft request' (duration: 396.116688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.638569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.549571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.639064Z","caller":"traceutil/trace.go:171","msg":"trace[1477740471] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:26; }","duration":"401.074666ms","start":"2026-08-28T04:04:00.237975Z","end":"2026-08-28T04:04:00.63905Z","steps":["trace[1477740471] 'agreement among raft nodes before linearized reading' (duration: 400.54385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.639078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.24156Z","time spent":"397.473944ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":947,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.639111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.237957Z","time spent":"401.143307ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-08-28T04:04:00.919251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.217662ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:00.919322Z","caller":"traceutil/trace.go:171","msg":"trace[879688840] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"276.968674ms","start":"2026-08-28T04:04:00.642341Z","end":"2026-08-28T04:04:00.91931Z","steps":["trace[879688840] 'process raft request' (duration: 35.64766ms)","trace[879688840] 'compare' (duration: 241.119301ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:00.959246Z","caller":"traceutil/trace.go:171","msg":"trace[74613851] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"316.364418ms","start":"2026-08-28T04:04:00.642852Z","end":"2026-08-28T04:04:00.959216Z","steps":["trace[74613851] 'process raft request' (duration: 316.044344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.959471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.642836Z","time spent":"316.55981ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.959579Z","caller":"traceutil/trace.go:171","msg":"trace[670590182] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"316.514049ms","start":"2026-08-28T04:04:00.642912Z","end":"2026-08-28T04:04:00.959426Z","steps":["trace[670590182] 'process raft request' (duration: 316.103194ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.959676Z","caller":"traceutil/trace.go:171","msg":"trace[1243431216] linearizableReadLoop","detail":"{readStateIndex:35; appliedIndex:30; }","duration":"315.391156ms","start":"2026-08-28T04:04:00.644221Z","end":"2026-08-28T04:04:00.959612Z","steps":["trace[1243431216] 'read index received' (duration: 33.794989ms)","trace[1243431216] 'applied index is now lower than readState.Index' (duration: 281.595157ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:00.959878Z","caller":"traceutil/trace.go:171","msg":"trace[457586945] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"316.814523ms","start":"2026-08-28T04:04:00.643008Z","end":"2026-08-28T04:04:00.959823Z","steps":["trace[457586945] 'process raft request' (duration: 316.168025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.959953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.642896Z","time spent":"316.877073ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":713,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.959945Z","caller":"traceutil/trace.go:171","msg":"trace[1640154077] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"314.447615ms","start":"2026-08-28T04:04:00.645479Z","end":"2026-08-28T04:04:00.959927Z","steps":["trace[1640154077] 'process raft request' (duration: 314.03293ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.960125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.642997Z","time spent":"316.980905ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.960089Z","caller":"traceutil/trace.go:171","msg":"trace[509707662] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"316.969775ms","start":"2026-08-28T04:04:00.643102Z","end":"2026-08-28T04:04:00.960072Z","steps":["trace[509707662] 'process raft request' (duration: 316.128265ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.960267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.645457Z","time spent":"314.665777ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.960285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.643079Z","time spent":"317.168187ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":926,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.960555Z","caller":"traceutil/trace.go:171","msg":"trace[834062515] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"315.335675ms","start":"2026-08-28T04:04:00.645188Z","end":"2026-08-28T04:04:00.960524Z","steps":["trace[834062515] 'process raft request' (duration: 314.226942ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.960672Z","caller":"traceutil/trace.go:171","msg":"trace[662441856] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"316.144824ms","start":"2026-08-28T04:04:00.644412Z","end":"2026-08-28T04:04:00.960557Z","steps":["trace[662441856] 'process raft request' (duration: 314.849549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.960782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.493049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:00.960804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.644394Z","time spent":"316.374618ms","remote":"127.0.0.1:52516","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:00.960902Z","caller":"traceutil/trace.go:171","msg":"trace[559993739] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:36; }","duration":"316.685341ms","start":"2026-08-28T04:04:00.64419Z","end":"2026-08-28T04:04:00.960875Z","steps":["trace[559993739] 'agreement among raft nodes before linearized reading' (duration: 316.386198ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.961303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.644175Z","time spent":"317.121406ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":0,"response size":27,"request content":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" "} {"level":"info","ts":"2026-08-28T04:04:00.961048Z","caller":"traceutil/trace.go:171","msg":"trace[23667942] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"316.381677ms","start":"2026-08-28T04:04:00.644656Z","end":"2026-08-28T04:04:00.961037Z","steps":["trace[23667942] 'process raft request' (duration: 314.642197ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.961484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.644643Z","time spent":"316.769903ms","remote":"127.0.0.1:52494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.960641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.645178Z","time spent":"315.418116ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":959,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:00.961017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.823961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.961836Z","caller":"traceutil/trace.go:171","msg":"trace[518714461] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:36; }","duration":"316.665211ms","start":"2026-08-28T04:04:00.645155Z","end":"2026-08-28T04:04:00.96182Z","steps":["trace[518714461] 'agreement among raft nodes before linearized reading' (duration: 315.826721ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.961928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.645142Z","time spent":"316.769262ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-08-28T04:04:00.961174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.859124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.962108Z","caller":"traceutil/trace.go:171","msg":"trace[821671090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:36; }","duration":"222.814735ms","start":"2026-08-28T04:04:00.739276Z","end":"2026-08-28T04:04:00.962091Z","steps":["trace[821671090] 'agreement among raft nodes before linearized reading' (duration: 221.878763ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.961207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.216265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:00.962316Z","caller":"traceutil/trace.go:171","msg":"trace[2129774967] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:36; }","duration":"266.342058ms","start":"2026-08-28T04:04:00.695954Z","end":"2026-08-28T04:04:00.962296Z","steps":["trace[2129774967] 'agreement among raft nodes before linearized reading' (duration: 265.236575ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:00.959914Z","caller":"traceutil/trace.go:171","msg":"trace[1826634229] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"314.732928ms","start":"2026-08-28T04:04:00.645097Z","end":"2026-08-28T04:04:00.95983Z","steps":["trace[1826634229] 'process raft request' (duration: 314.274833ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.96257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.645083Z","time spent":"317.44707ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":983,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:01.440976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.715471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:01.441048Z","caller":"traceutil/trace.go:171","msg":"trace[84910889] linearizableReadLoop","detail":"{readStateIndex:41; appliedIndex:40; }","duration":"474.893574ms","start":"2026-08-28T04:04:00.966143Z","end":"2026-08-28T04:04:01.441037Z","steps":["trace[84910889] 'read index received' (duration: 154.078462ms)","trace[84910889] 'applied index is now lower than readState.Index' (duration: 320.814312ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:01.441138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.990425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.441158Z","caller":"traceutil/trace.go:171","msg":"trace[963907127] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:37; }","duration":"475.033006ms","start":"2026-08-28T04:04:00.966119Z","end":"2026-08-28T04:04:01.441152Z","steps":["trace[963907127] 'agreement among raft nodes before linearized reading' (duration: 474.964185ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.441178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.966109Z","time spent":"475.063716ms","remote":"127.0.0.1:52494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-cluster-critical\" "} {"level":"info","ts":"2026-08-28T04:04:01.441353Z","caller":"traceutil/trace.go:171","msg":"trace[1495153224] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"476.431172ms","start":"2026-08-28T04:04:00.964639Z","end":"2026-08-28T04:04:01.44107Z","steps":["trace[1495153224] 'process raft request' (duration: 155.564219ms)","trace[1495153224] 'compare' (duration: 320.599729ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:01.441682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.964605Z","time spent":"476.984559ms","remote":"127.0.0.1:52666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:01.705362Z","caller":"traceutil/trace.go:171","msg":"trace[1342926450] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"739.002445ms","start":"2026-08-28T04:04:00.966321Z","end":"2026-08-28T04:04:01.705323Z","steps":["trace[1342926450] 'process raft request' (duration: 652.766549ms)","trace[1342926450] 'compare' (duration: 85.972543ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:01.705412Z","caller":"traceutil/trace.go:171","msg":"trace[80405491] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:41; }","duration":"264.324024ms","start":"2026-08-28T04:04:01.441062Z","end":"2026-08-28T04:04:01.705386Z","steps":["trace[80405491] 'read index received' (duration: 178.037537ms)","trace[80405491] 'applied index is now lower than readState.Index' (duration: 86.285507ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:01.705519Z","caller":"traceutil/trace.go:171","msg":"trace[1353757543] transaction","detail":"{read_only:false; response_revision:39; number_of_response:1; }","duration":"739.111026ms","start":"2026-08-28T04:04:00.966399Z","end":"2026-08-28T04:04:01.70551Z","steps":["trace[1353757543] 'process raft request' (duration: 738.832973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.705513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.966303Z","time spent":"739.133016ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":350,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:01.705587Z","caller":"traceutil/trace.go:171","msg":"trace[1267764747] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"738.944774ms","start":"2026-08-28T04:04:00.966623Z","end":"2026-08-28T04:04:01.705568Z","steps":["trace[1267764747] 'process raft request' (duration: 738.721161ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.705672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.966607Z","time spent":"739.028815ms","remote":"127.0.0.1:52574","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":711,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:01.705763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.770792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.705801Z","caller":"traceutil/trace.go:171","msg":"trace[1050044910] range","detail":"{range_begin:/registry/clusterroles/admin; range_end:; response_count:0; response_revision:40; }","duration":"738.889093ms","start":"2026-08-28T04:04:00.966904Z","end":"2026-08-28T04:04:01.705793Z","steps":["trace[1050044910] 'agreement among raft nodes before linearized reading' (duration: 738.719751ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.705812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"686.818791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-28T04:04:01.70583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.966893Z","time spent":"738.931604ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/admin\" "} {"level":"info","ts":"2026-08-28T04:04:01.705872Z","caller":"traceutil/trace.go:171","msg":"trace[82575867] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:40; }","duration":"686.884462ms","start":"2026-08-28T04:04:01.018973Z","end":"2026-08-28T04:04:01.705857Z","steps":["trace[82575867] 'agreement among raft nodes before linearized reading' (duration: 686.654659ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.705919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:01.018922Z","time spent":"686.986833ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":30,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/default\" "} {"level":"warn","ts":"2026-08-28T04:04:01.705602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.966394Z","time spent":"739.149676ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3004,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:01.779155Z","caller":"traceutil/trace.go:171","msg":"trace[2070651609] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"335.783847ms","start":"2026-08-28T04:04:01.44335Z","end":"2026-08-28T04:04:01.779134Z","steps":["trace[2070651609] 'process raft request' (duration: 335.651025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.779402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:01.443328Z","time spent":"335.969409ms","remote":"127.0.0.1:52494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:01.779421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.887088ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.779765Z","caller":"traceutil/trace.go:171","msg":"trace[1004663747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:41; }","duration":"240.286533ms","start":"2026-08-28T04:04:01.539458Z","end":"2026-08-28T04:04:01.779745Z","steps":["trace[1004663747] 'agreement among raft nodes before linearized reading' (duration: 239.745617ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.916994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.189178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:01.917082Z","caller":"traceutil/trace.go:171","msg":"trace[1771587727] linearizableReadLoop","detail":"{readStateIndex:46; appliedIndex:45; }","duration":"137.953669ms","start":"2026-08-28T04:04:01.779113Z","end":"2026-08-28T04:04:01.917067Z","steps":["trace[1771587727] 'read index received' (duration: 31.703319ms)","trace[1771587727] 'applied index is now lower than readState.Index' (duration: 106.2493ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:01.917272Z","caller":"traceutil/trace.go:171","msg":"trace[369286044] transaction","detail":"{read_only:false; response_revision:42; number_of_response:1; }","duration":"208.086657ms","start":"2026-08-28T04:04:01.709158Z","end":"2026-08-28T04:04:01.917244Z","steps":["trace[369286044] 'process raft request' (duration: 101.588585ms)","trace[369286044] 'compare' (duration: 106.045337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:01.917267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.889287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.917344Z","caller":"traceutil/trace.go:171","msg":"trace[1521597760] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:42; }","duration":"155.015908ms","start":"2026-08-28T04:04:01.762313Z","end":"2026-08-28T04:04:01.917329Z","steps":["trace[1521597760] 'agreement among raft nodes before linearized reading' (duration: 154.802075ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.917602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.418451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.917627Z","caller":"traceutil/trace.go:171","msg":"trace[342075061] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:42; }","duration":"208.473121ms","start":"2026-08-28T04:04:01.709147Z","end":"2026-08-28T04:04:01.917621Z","steps":["trace[342075061] 'agreement among raft nodes before linearized reading' (duration: 208.426001ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.917653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.886344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/default/\" range_end:\"/registry/resourcequotas/default0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.917791Z","caller":"traceutil/trace.go:171","msg":"trace[2113955240] range","detail":"{range_begin:/registry/resourcequotas/default/; range_end:/registry/resourcequotas/default0; response_count:0; response_revision:42; }","duration":"208.081287ms","start":"2026-08-28T04:04:01.709696Z","end":"2026-08-28T04:04:01.917777Z","steps":["trace[2113955240] 'agreement among raft nodes before linearized reading' (duration: 207.428889ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:01.940635Z","caller":"traceutil/trace.go:171","msg":"trace[1525496755] transaction","detail":"{read_only:false; response_revision:43; number_of_response:1; }","duration":"140.478238ms","start":"2026-08-28T04:04:01.800128Z","end":"2026-08-28T04:04:01.940607Z","steps":["trace[1525496755] 'process raft request' (duration: 140.316056ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:01.940737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.028146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:01.940915Z","caller":"traceutil/trace.go:171","msg":"trace[1725325025] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:43; }","duration":"160.179378ms","start":"2026-08-28T04:04:01.780661Z","end":"2026-08-28T04:04:01.940841Z","steps":["trace[1725325025] 'agreement among raft nodes before linearized reading' (duration: 159.942735ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.147967Z","caller":"traceutil/trace.go:171","msg":"trace[925211810] linearizableReadLoop","detail":"{readStateIndex:49; appliedIndex:47; }","duration":"207.374292ms","start":"2026-08-28T04:04:01.940574Z","end":"2026-08-28T04:04:02.147948Z","steps":["trace[925211810] 'read index received' (duration: 113.261415ms)","trace[925211810] 'applied index is now lower than readState.Index' (duration: 94.111987ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:02.148064Z","caller":"traceutil/trace.go:171","msg":"trace[1442378750] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"226.87444ms","start":"2026-08-28T04:04:01.92115Z","end":"2026-08-28T04:04:02.148025Z","steps":["trace[1442378750] 'process raft request' (duration: 226.755879ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.148071Z","caller":"traceutil/trace.go:171","msg":"trace[587290861] transaction","detail":"{read_only:false; response_revision:44; number_of_response:1; }","duration":"228.403317ms","start":"2026-08-28T04:04:01.919612Z","end":"2026-08-28T04:04:02.148015Z","steps":["trace[587290861] 'process raft request' (duration: 134.28405ms)","trace[587290861] 'compare' (duration: 93.870454ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:02.14819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.63939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.148257Z","caller":"traceutil/trace.go:171","msg":"trace[1922333793] range","detail":"{range_begin:/registry/clusterroles/edit; range_end:; response_count:0; response_revision:45; }","duration":"228.753561ms","start":"2026-08-28T04:04:01.919491Z","end":"2026-08-28T04:04:02.148245Z","steps":["trace[1922333793] 'agreement among raft nodes before linearized reading' (duration: 228.537759ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.148399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.761378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903a9f1\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.148503Z","caller":"traceutil/trace.go:171","msg":"trace[1208101805] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903a9f1; range_end:; response_count:0; response_revision:45; }","duration":"226.96915ms","start":"2026-08-28T04:04:01.921518Z","end":"2026-08-28T04:04:02.148487Z","steps":["trace[1208101805] 'agreement among raft nodes before linearized reading' (duration: 226.560406ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.398964Z","caller":"traceutil/trace.go:171","msg":"trace[190284181] linearizableReadLoop","detail":"{readStateIndex:55; appliedIndex:53; }","duration":"203.298367ms","start":"2026-08-28T04:04:02.195647Z","end":"2026-08-28T04:04:02.398946Z","steps":["trace[190284181] 'read index received' (duration: 152.684613ms)","trace[190284181] 'applied index is now lower than readState.Index' (duration: 50.612984ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:02.399096Z","caller":"traceutil/trace.go:171","msg":"trace[1884472788] transaction","detail":"{read_only:false; response_revision:49; number_of_response:1; }","duration":"216.625741ms","start":"2026-08-28T04:04:02.182419Z","end":"2026-08-28T04:04:02.399045Z","steps":["trace[1884472788] 'process raft request' (duration: 165.961426ms)","trace[1884472788] 'compare' (duration: 50.387762ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:02.399232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.56874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/cluster-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.399283Z","caller":"traceutil/trace.go:171","msg":"trace[67044938] range","detail":"{range_begin:/registry/clusterroles/cluster-admin; range_end:; response_count:0; response_revision:50; }","duration":"203.660182ms","start":"2026-08-28T04:04:02.195614Z","end":"2026-08-28T04:04:02.399274Z","steps":["trace[67044938] 'agreement among raft nodes before linearized reading' (duration: 203.547731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.399271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.392329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903e21d\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.399337Z","caller":"traceutil/trace.go:171","msg":"trace[1024210259] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903e21d; range_end:; response_count:0; response_revision:50; }","duration":"203.47383ms","start":"2026-08-28T04:04:02.195852Z","end":"2026-08-28T04:04:02.399326Z","steps":["trace[1024210259] 'agreement among raft nodes before linearized reading' (duration: 203.385799ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.399139Z","caller":"traceutil/trace.go:171","msg":"trace[488380750] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"215.735581ms","start":"2026-08-28T04:04:02.183395Z","end":"2026-08-28T04:04:02.39913Z","steps":["trace[488380750] 'process raft request' (duration: 215.513509ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.572283Z","caller":"traceutil/trace.go:171","msg":"trace[867343999] transaction","detail":"{read_only:false; response_revision:52; number_of_response:1; }","duration":"169.551809ms","start":"2026-08-28T04:04:02.402699Z","end":"2026-08-28T04:04:02.572251Z","steps":["trace[867343999] 'process raft request' (duration: 160.272131ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.572417Z","caller":"traceutil/trace.go:171","msg":"trace[192506741] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"169.491818ms","start":"2026-08-28T04:04:02.402842Z","end":"2026-08-28T04:04:02.572334Z","steps":["trace[192506741] 'process raft request' (duration: 169.359546ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.572308Z","caller":"traceutil/trace.go:171","msg":"trace[1831404143] linearizableReadLoop","detail":"{readStateIndex:59; appliedIndex:56; }","duration":"102.861598ms","start":"2026-08-28T04:04:02.469428Z","end":"2026-08-28T04:04:02.57229Z","steps":["trace[1831404143] 'read index received' (duration: 93.568761ms)","trace[1831404143] 'applied index is now lower than readState.Index' (duration: 9.292107ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:02.572716Z","caller":"traceutil/trace.go:171","msg":"trace[55435720] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"168.495106ms","start":"2026-08-28T04:04:02.404151Z","end":"2026-08-28T04:04:02.572646Z","steps":["trace[55435720] 'process raft request' (duration: 168.086031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.575851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.408099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903f767\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.575893Z","caller":"traceutil/trace.go:171","msg":"trace[55425091] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903f767; range_end:; response_count:0; response_revision:54; }","duration":"106.498949ms","start":"2026-08-28T04:04:02.469383Z","end":"2026-08-28T04:04:02.575882Z","steps":["trace[55425091] 'agreement among raft nodes before linearized reading' (duration: 102.961339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.924089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.291471ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:02.924248Z","caller":"traceutil/trace.go:171","msg":"trace[551874582] transaction","detail":"{read_only:false; response_revision:57; number_of_response:1; }","duration":"337.944143ms","start":"2026-08-28T04:04:02.586272Z","end":"2026-08-28T04:04:02.924216Z","steps":["trace[551874582] 'process raft request' (duration: 183.446599ms)","trace[551874582] 'compare' (duration: 154.18694ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:02.924316Z","caller":"traceutil/trace.go:171","msg":"trace[513223277] transaction","detail":"{read_only:false; response_revision:59; number_of_response:1; }","duration":"315.582845ms","start":"2026-08-28T04:04:02.608708Z","end":"2026-08-28T04:04:02.924291Z","steps":["trace[513223277] 'process raft request' (duration: 315.507324ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:02.924372Z","caller":"traceutil/trace.go:171","msg":"trace[1249717930] linearizableReadLoop","detail":"{readStateIndex:63; appliedIndex:61; }","duration":"316.714917ms","start":"2026-08-28T04:04:02.607646Z","end":"2026-08-28T04:04:02.924361Z","steps":["trace[1249717930] 'read index received' (duration: 162.057031ms)","trace[1249717930] 'applied index is now lower than readState.Index' (duration: 154.656746ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:02.924401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:02.586238Z","time spent":"338.088814ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":634,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:02.924463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.814569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903a9f1\" ","response":"range_response_count:1 size:654"} {"level":"warn","ts":"2026-08-28T04:04:02.924487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.090304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:02.924485Z","caller":"traceutil/trace.go:171","msg":"trace[298237945] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903a9f1; range_end:; response_count:1; response_revision:59; }","duration":"316.866459ms","start":"2026-08-28T04:04:02.607611Z","end":"2026-08-28T04:04:02.924478Z","steps":["trace[298237945] 'agreement among raft nodes before linearized reading' (duration: 316.814648ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.924519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:02.607596Z","time spent":"316.91878ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":677,"request content":"key:\"/registry/events/default/instance.18cfdc556903a9f1\" "} {"level":"info","ts":"2026-08-28T04:04:02.92452Z","caller":"traceutil/trace.go:171","msg":"trace[1760396867] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:59; }","duration":"286.197435ms","start":"2026-08-28T04:04:02.638311Z","end":"2026-08-28T04:04:02.924509Z","steps":["trace[1760396867] 'agreement among raft nodes before linearized reading' (duration: 286.131454ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.924403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:02.608681Z","time spent":"315.674936ms","remote":"127.0.0.1:52572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1216,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:02.924306Z","caller":"traceutil/trace.go:171","msg":"trace[631120238] transaction","detail":"{read_only:false; response_revision:58; number_of_response:1; }","duration":"336.571097ms","start":"2026-08-28T04:04:02.587709Z","end":"2026-08-28T04:04:02.92428Z","steps":["trace[631120238] 'process raft request' (duration: 336.464305ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:02.924697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:02.587699Z","time spent":"336.92093ms","remote":"127.0.0.1:52572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":826,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:03.130653Z","caller":"traceutil/trace.go:171","msg":"trace[198369235] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"202.392253ms","start":"2026-08-28T04:04:02.928237Z","end":"2026-08-28T04:04:03.13063Z","steps":["trace[198369235] 'process raft request' (duration: 118.489753ms)","trace[198369235] 'compare' (duration: 83.757188ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:03.130952Z","caller":"traceutil/trace.go:171","msg":"trace[777091050] linearizableReadLoop","detail":"{readStateIndex:68; appliedIndex:65; }","duration":"161.299038ms","start":"2026-08-28T04:04:02.969634Z","end":"2026-08-28T04:04:03.130933Z","steps":["trace[777091050] 'read index received' (duration: 76.987974ms)","trace[777091050] 'applied index is now lower than readState.Index' (duration: 84.310104ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:03.130956Z","caller":"traceutil/trace.go:171","msg":"trace[1228006279] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"202.650285ms","start":"2026-08-28T04:04:02.928274Z","end":"2026-08-28T04:04:03.130924Z","steps":["trace[1228006279] 'process raft request' (duration: 202.324721ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:03.13104Z","caller":"traceutil/trace.go:171","msg":"trace[957834585] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"201.900527ms","start":"2026-08-28T04:04:02.929132Z","end":"2026-08-28T04:04:03.131032Z","steps":["trace[957834585] 'process raft request' (duration: 201.624264ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:03.131155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.50781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903e21d\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-28T04:04:03.131215Z","caller":"traceutil/trace.go:171","msg":"trace[1538378531] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903e21d; range_end:; response_count:1; response_revision:63; }","duration":"161.616532ms","start":"2026-08-28T04:04:02.969584Z","end":"2026-08-28T04:04:03.131201Z","steps":["trace[1538378531] 'agreement among raft nodes before linearized reading' (duration: 161.44853ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:03.262414Z","caller":"traceutil/trace.go:171","msg":"trace[1085809469] transaction","detail":"{read_only:false; response_revision:67; number_of_response:1; }","duration":"120.464047ms","start":"2026-08-28T04:04:03.141909Z","end":"2026-08-28T04:04:03.262373Z","steps":["trace[1085809469] 'process raft request' (duration: 97.234321ms)","trace[1085809469] 'compare' (duration: 22.71572ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:03.262558Z","caller":"traceutil/trace.go:171","msg":"trace[422817614] linearizableReadLoop","detail":"{readStateIndex:72; appliedIndex:69; }","duration":"119.930131ms","start":"2026-08-28T04:04:03.142619Z","end":"2026-08-28T04:04:03.262549Z","steps":["trace[422817614] 'read index received' (duration: 76.066359ms)","trace[422817614] 'applied index is now lower than readState.Index' (duration: 43.861912ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:03.262663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.028842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18cfdc556903f767\" ","response":"range_response_count:1 size:648"} {"level":"info","ts":"2026-08-28T04:04:03.262737Z","caller":"traceutil/trace.go:171","msg":"trace[916608929] range","detail":"{range_begin:/registry/events/default/instance.18cfdc556903f767; range_end:; response_count:1; response_revision:69; }","duration":"120.132873ms","start":"2026-08-28T04:04:03.142592Z","end":"2026-08-28T04:04:03.262725Z","steps":["trace[916608929] 'agreement among raft nodes before linearized reading' (duration: 119.995271ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:03.897401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.953195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:03.897545Z","caller":"traceutil/trace.go:171","msg":"trace[1005340091] transaction","detail":"{read_only:false; response_revision:103; number_of_response:1; }","duration":"186.916767ms","start":"2026-08-28T04:04:03.710616Z","end":"2026-08-28T04:04:03.897533Z","steps":["trace[1005340091] 'process raft request' (duration: 186.860726ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:03.897558Z","caller":"traceutil/trace.go:171","msg":"trace[207390126] transaction","detail":"{read_only:false; response_revision:102; number_of_response:1; }","duration":"188.656137ms","start":"2026-08-28T04:04:03.70888Z","end":"2026-08-28T04:04:03.897536Z","steps":["trace[207390126] 'process raft request' (duration: 87.50327ms)","trace[207390126] 'compare' (duration: 100.751182ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:04.406986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.865139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:04.407093Z","caller":"traceutil/trace.go:171","msg":"trace[1243107386] linearizableReadLoop","detail":"{readStateIndex:117; appliedIndex:116; }","duration":"289.736872ms","start":"2026-08-28T04:04:04.117341Z","end":"2026-08-28T04:04:04.407078Z","steps":["trace[1243107386] 'read index received' (duration: 145.69715ms)","trace[1243107386] 'applied index is now lower than readState.Index' (duration: 144.038632ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:04.407184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.839233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:kube-aggregator\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:04.407211Z","caller":"traceutil/trace.go:171","msg":"trace[356701960] range","detail":"{range_begin:/registry/clusterroles/system:kube-aggregator; range_end:; response_count:0; response_revision:112; }","duration":"289.890933ms","start":"2026-08-28T04:04:04.117312Z","end":"2026-08-28T04:04:04.407202Z","steps":["trace[356701960] 'agreement among raft nodes before linearized reading' (duration: 289.807602ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:04.407288Z","caller":"traceutil/trace.go:171","msg":"trace[1382401474] transaction","detail":"{read_only:false; response_revision:112; number_of_response:1; }","duration":"312.466829ms","start":"2026-08-28T04:04:04.094808Z","end":"2026-08-28T04:04:04.407275Z","steps":["trace[1382401474] 'process raft request' (duration: 168.243436ms)","trace[1382401474] 'compare' (duration: 143.783288ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:04.407343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:04.094795Z","time spent":"312.519469ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:04.571999Z","caller":"traceutil/trace.go:171","msg":"trace[963731730] transaction","detail":"{read_only:false; response_revision:113; number_of_response:1; }","duration":"161.841412ms","start":"2026-08-28T04:04:04.410137Z","end":"2026-08-28T04:04:04.571978Z","steps":["trace[963731730] 'process raft request' (duration: 136.218551ms)","trace[963731730] 'compare' (duration: 25.516829ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:04.63613Z","caller":"traceutil/trace.go:171","msg":"trace[1089901117] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"224.817635ms","start":"2026-08-28T04:04:04.411281Z","end":"2026-08-28T04:04:04.636098Z","steps":["trace[1089901117] 'process raft request' (duration: 224.647103ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:04.968876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.506595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-28T04:04:04.968977Z","caller":"traceutil/trace.go:171","msg":"trace[374291720] transaction","detail":"{read_only:false; response_revision:115; number_of_response:1; }","duration":"329.703213ms","start":"2026-08-28T04:04:04.639254Z","end":"2026-08-28T04:04:04.968957Z","steps":["trace[374291720] 'process raft request' (duration: 156.056346ms)","trace[374291720] 'compare' (duration: 173.355474ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:04.969024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:04.639234Z","time spent":"329.771504ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:05.026491Z","caller":"traceutil/trace.go:171","msg":"trace[1103915546] linearizableReadLoop","detail":"{readStateIndex:123; appliedIndex:119; }","duration":"287.342331ms","start":"2026-08-28T04:04:04.739131Z","end":"2026-08-28T04:04:05.026473Z","steps":["trace[1103915546] 'read index received' (duration: 56.195146ms)","trace[1103915546] 'applied index is now lower than readState.Index' (duration: 231.146575ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:05.02653Z","caller":"traceutil/trace.go:171","msg":"trace[986608628] transaction","detail":"{read_only:false; response_revision:117; number_of_response:1; }","duration":"386.818188ms","start":"2026-08-28T04:04:04.639681Z","end":"2026-08-28T04:04:05.0265Z","steps":["trace[986608628] 'process raft request' (duration: 386.739477ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.026585Z","caller":"traceutil/trace.go:171","msg":"trace[680966496] transaction","detail":"{read_only:false; response_revision:118; number_of_response:1; }","duration":"386.342862ms","start":"2026-08-28T04:04:04.640235Z","end":"2026-08-28T04:04:05.026578Z","steps":["trace[680966496] 'process raft request' (duration: 386.212661ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:05.026665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:04.640217Z","time spent":"386.389624ms","remote":"127.0.0.1:52474","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1026,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:05.026688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:04.639666Z","time spent":"386.931869ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":749,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:05.026734Z","caller":"traceutil/trace.go:171","msg":"trace[1705933306] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"387.160131ms","start":"2026-08-28T04:04:04.63953Z","end":"2026-08-28T04:04:05.02669Z","steps":["trace[1705933306] 'process raft request' (duration: 386.799067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:05.026854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:04.639511Z","time spent":"387.287844ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:05.026931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.794856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-28T04:04:05.026958Z","caller":"traceutil/trace.go:171","msg":"trace[321962347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:118; }","duration":"287.865187ms","start":"2026-08-28T04:04:04.739085Z","end":"2026-08-28T04:04:05.02695Z","steps":["trace[321962347] 'agreement among raft nodes before linearized reading' (duration: 287.820487ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.267142Z","caller":"traceutil/trace.go:171","msg":"trace[122050969] transaction","detail":"{read_only:false; response_revision:120; number_of_response:1; }","duration":"235.692274ms","start":"2026-08-28T04:04:05.031408Z","end":"2026-08-28T04:04:05.2671Z","steps":["trace[122050969] 'process raft request' (duration: 180.483565ms)","trace[122050969] 'compare' (duration: 54.874245ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:05.267222Z","caller":"traceutil/trace.go:171","msg":"trace[1447034778] transaction","detail":"{read_only:false; response_revision:122; number_of_response:1; }","duration":"231.506817ms","start":"2026-08-28T04:04:05.035664Z","end":"2026-08-28T04:04:05.267171Z","steps":["trace[1447034778] 'process raft request' (duration: 231.385666ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.267302Z","caller":"traceutil/trace.go:171","msg":"trace[1915972096] transaction","detail":"{read_only:false; response_revision:123; number_of_response:1; }","duration":"196.200681ms","start":"2026-08-28T04:04:05.071088Z","end":"2026-08-28T04:04:05.267288Z","steps":["trace[1915972096] 'process raft request' (duration: 195.998439ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.267163Z","caller":"traceutil/trace.go:171","msg":"trace[1427994939] transaction","detail":"{read_only:false; response_revision:121; number_of_response:1; }","duration":"232.895393ms","start":"2026-08-28T04:04:05.034238Z","end":"2026-08-28T04:04:05.267133Z","steps":["trace[1427994939] 'process raft request' (duration: 232.720071ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.556125Z","caller":"traceutil/trace.go:171","msg":"trace[1692287658] transaction","detail":"{read_only:false; response_revision:128; number_of_response:1; }","duration":"211.979758ms","start":"2026-08-28T04:04:05.344121Z","end":"2026-08-28T04:04:05.556101Z","steps":["trace[1692287658] 'process raft request' (duration: 211.903547ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:05.556157Z","caller":"traceutil/trace.go:171","msg":"trace[384100412] transaction","detail":"{read_only:false; response_revision:127; number_of_response:1; }","duration":"212.536664ms","start":"2026-08-28T04:04:05.343599Z","end":"2026-08-28T04:04:05.556136Z","steps":["trace[384100412] 'process raft request' (duration: 126.787333ms)","trace[384100412] 'compare' (duration: 85.479108ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:05.803668Z","caller":"traceutil/trace.go:171","msg":"trace[543960890] transaction","detail":"{read_only:false; response_revision:130; number_of_response:1; }","duration":"243.041197ms","start":"2026-08-28T04:04:05.560601Z","end":"2026-08-28T04:04:05.803642Z","steps":["trace[543960890] 'process raft request' (duration: 169.813405ms)","trace[543960890] 'compare' (duration: 73.07453ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:05.839227Z","caller":"traceutil/trace.go:171","msg":"trace[509079168] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"193.258837ms","start":"2026-08-28T04:04:05.645936Z","end":"2026-08-28T04:04:05.839195Z","steps":["trace[509079168] 'process raft request' (duration: 193.122276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:05.839278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.075493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:05.839345Z","caller":"traceutil/trace.go:171","msg":"trace[1397200090] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:131; }","duration":"100.186984ms","start":"2026-08-28T04:04:05.739142Z","end":"2026-08-28T04:04:05.839329Z","steps":["trace[1397200090] 'agreement among raft nodes before linearized reading' (duration: 100.060162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:06.119957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.173834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:06.120054Z","caller":"traceutil/trace.go:171","msg":"trace[1044533376] linearizableReadLoop","detail":"{readStateIndex:140; appliedIndex:139; }","duration":"230.796266ms","start":"2026-08-28T04:04:05.889227Z","end":"2026-08-28T04:04:06.120023Z","steps":["trace[1044533376] 'read index received' (duration: 95.509111ms)","trace[1044533376] 'applied index is now lower than readState.Index' (duration: 135.286265ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:06.120106Z","caller":"traceutil/trace.go:171","msg":"trace[615543521] transaction","detail":"{read_only:false; response_revision:135; number_of_response:1; }","duration":"254.765636ms","start":"2026-08-28T04:04:05.865331Z","end":"2026-08-28T04:04:06.120096Z","steps":["trace[615543521] 'process raft request' (duration: 119.39543ms)","trace[615543521] 'compare' (duration: 135.060353ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:06.120182Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.945188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:06.120246Z","caller":"traceutil/trace.go:171","msg":"trace[121133651] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:legacy-unknown-approver; range_end:; response_count:0; response_revision:135; }","duration":"231.04476ms","start":"2026-08-28T04:04:05.889182Z","end":"2026-08-28T04:04:06.120227Z","steps":["trace[121133651] 'agreement among raft nodes before linearized reading' (duration: 230.929008ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:06.146888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.581166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:06.146951Z","caller":"traceutil/trace.go:171","msg":"trace[989226403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:136; }","duration":"108.682918ms","start":"2026-08-28T04:04:06.038254Z","end":"2026-08-28T04:04:06.146937Z","steps":["trace[989226403] 'agreement among raft nodes before linearized reading' (duration: 108.569357ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:06.146949Z","caller":"traceutil/trace.go:171","msg":"trace[1200852576] transaction","detail":"{read_only:false; response_revision:136; number_of_response:1; }","duration":"256.926057ms","start":"2026-08-28T04:04:05.88999Z","end":"2026-08-28T04:04:06.146916Z","steps":["trace[1200852576] 'process raft request' (duration: 256.704974ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:06.359589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.725133ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:06.359784Z","caller":"traceutil/trace.go:171","msg":"trace[697969939] linearizableReadLoop","detail":"{readStateIndex:146; appliedIndex:144; }","duration":"137.170445ms","start":"2026-08-28T04:04:06.222594Z","end":"2026-08-28T04:04:06.359765Z","steps":["trace[697969939] 'read index received' (duration: 24.209259ms)","trace[697969939] 'applied index is now lower than readState.Index' (duration: 112.960236ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:06.359921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.316016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5511"} {"level":"info","ts":"2026-08-28T04:04:06.359961Z","caller":"traceutil/trace.go:171","msg":"trace[124934837] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:141; }","duration":"137.389737ms","start":"2026-08-28T04:04:06.222559Z","end":"2026-08-28T04:04:06.359949Z","steps":["trace[124934837] 'agreement among raft nodes before linearized reading' (duration: 137.263235ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:06.36007Z","caller":"traceutil/trace.go:171","msg":"trace[1106292628] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"170.89499ms","start":"2026-08-28T04:04:06.189115Z","end":"2026-08-28T04:04:06.36001Z","steps":["trace[1106292628] 'process raft request' (duration: 57.693171ms)","trace[1106292628] 'compare' (duration: 112.560031ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:06.360101Z","caller":"traceutil/trace.go:171","msg":"trace[1904741459] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"170.640756ms","start":"2026-08-28T04:04:06.18944Z","end":"2026-08-28T04:04:06.36008Z","steps":["trace[1904741459] 'process raft request' (duration: 170.270672ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:06.551711Z","caller":"traceutil/trace.go:171","msg":"trace[1986081805] linearizableReadLoop","detail":"{readStateIndex:150; appliedIndex:149; }","duration":"121.396069ms","start":"2026-08-28T04:04:06.430292Z","end":"2026-08-28T04:04:06.551688Z","steps":["trace[1986081805] 'read index received' (duration: 72.454536ms)","trace[1986081805] 'applied index is now lower than readState.Index' (duration: 48.940543ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:06.551735Z","caller":"traceutil/trace.go:171","msg":"trace[1536657800] transaction","detail":"{read_only:false; response_revision:145; number_of_response:1; }","duration":"172.923982ms","start":"2026-08-28T04:04:06.378789Z","end":"2026-08-28T04:04:06.551713Z","steps":["trace[1536657800] 'process raft request' (duration: 123.907577ms)","trace[1536657800] 'compare' (duration: 48.862903ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:06.551829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.517291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:06.551865Z","caller":"traceutil/trace.go:171","msg":"trace[862139959] range","detail":"{range_begin:/registry/clusterroles/system:certificates.k8s.io:kube-apiserver-client-kubelet-approver; range_end:; response_count:0; response_revision:145; }","duration":"121.593801ms","start":"2026-08-28T04:04:06.43026Z","end":"2026-08-28T04:04:06.551854Z","steps":["trace[862139959] 'agreement among raft nodes before linearized reading' (duration: 121.50911ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:06.811828Z","caller":"traceutil/trace.go:171","msg":"trace[2081178829] linearizableReadLoop","detail":"{readStateIndex:154; appliedIndex:153; }","duration":"200.474007ms","start":"2026-08-28T04:04:06.611335Z","end":"2026-08-28T04:04:06.811809Z","steps":["trace[2081178829] 'read index received' (duration: 118.80254ms)","trace[2081178829] 'applied index is now lower than readState.Index' (duration: 81.670717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:06.811941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.59663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:node-proxier\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:06.811967Z","caller":"traceutil/trace.go:171","msg":"trace[1140354113] range","detail":"{range_begin:/registry/clusterroles/system:node-proxier; range_end:; response_count:0; response_revision:149; }","duration":"200.63519ms","start":"2026-08-28T04:04:06.611325Z","end":"2026-08-28T04:04:06.81196Z","steps":["trace[1140354113] 'agreement among raft nodes before linearized reading' (duration: 200.557309ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:06.812106Z","caller":"traceutil/trace.go:171","msg":"trace[2040070467] transaction","detail":"{read_only:false; response_revision:149; number_of_response:1; }","duration":"218.159874ms","start":"2026-08-28T04:04:06.593909Z","end":"2026-08-28T04:04:06.812069Z","steps":["trace[2040070467] 'process raft request' (duration: 136.171793ms)","trace[2040070467] 'compare' (duration: 81.637397ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:07.435525Z","caller":"traceutil/trace.go:171","msg":"trace[1984734216] transaction","detail":"{read_only:false; response_revision:159; number_of_response:1; }","duration":"132.275647ms","start":"2026-08-28T04:04:07.303226Z","end":"2026-08-28T04:04:07.435502Z","steps":["trace[1984734216] 'process raft request' (duration: 132.223746ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:07.435546Z","caller":"traceutil/trace.go:171","msg":"trace[679121322] transaction","detail":"{read_only:false; response_revision:158; number_of_response:1; }","duration":"133.216717ms","start":"2026-08-28T04:04:07.302298Z","end":"2026-08-28T04:04:07.435515Z","steps":["trace[679121322] 'process raft request' (duration: 126.177889ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:07.64053Z","caller":"traceutil/trace.go:171","msg":"trace[404299317] transaction","detail":"{read_only:false; response_revision:163; number_of_response:1; }","duration":"108.204402ms","start":"2026-08-28T04:04:07.5323Z","end":"2026-08-28T04:04:07.640504Z","steps":["trace[404299317] 'compare' (duration: 98.443845ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:07.916694Z","caller":"traceutil/trace.go:171","msg":"trace[1634706479] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"215.412463ms","start":"2026-08-28T04:04:07.701239Z","end":"2026-08-28T04:04:07.916652Z","steps":["trace[1634706479] 'process raft request' (duration: 181.922274ms)","trace[1634706479] 'compare' (duration: 33.285827ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:08.124096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.015692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:08.124207Z","caller":"traceutil/trace.go:171","msg":"trace[1330913232] transaction","detail":"{read_only:false; response_revision:171; number_of_response:1; }","duration":"112.880781ms","start":"2026-08-28T04:04:08.011306Z","end":"2026-08-28T04:04:08.124187Z","steps":["trace[1330913232] 'compare' (duration: 101.860681ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:08.491657Z","caller":"traceutil/trace.go:171","msg":"trace[471007056] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"142.197902ms","start":"2026-08-28T04:04:08.34943Z","end":"2026-08-28T04:04:08.491628Z","steps":["trace[471007056] 'process raft request' (duration: 88.790999ms)","trace[471007056] 'compare' (duration: 53.238291ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:08.867403Z","caller":"traceutil/trace.go:171","msg":"trace[172139276] transaction","detail":"{read_only:false; response_revision:191; number_of_response:1; }","duration":"118.1745ms","start":"2026-08-28T04:04:08.749202Z","end":"2026-08-28T04:04:08.867377Z","steps":["trace[172139276] 'process raft request' (duration: 30.753425ms)","trace[172139276] 'compare' (duration: 87.240043ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:09.048701Z","caller":"traceutil/trace.go:171","msg":"trace[210008276] linearizableReadLoop","detail":"{readStateIndex:198; appliedIndex:197; }","duration":"110.400851ms","start":"2026-08-28T04:04:08.938282Z","end":"2026-08-28T04:04:09.048683Z","steps":["trace[210008276] 'read index received' (duration: 39.984936ms)","trace[210008276] 'applied index is now lower than readState.Index' (duration: 70.415205ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:09.04873Z","caller":"traceutil/trace.go:171","msg":"trace[370480367] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"112.15089ms","start":"2026-08-28T04:04:08.936553Z","end":"2026-08-28T04:04:09.048704Z","steps":["trace[370480367] 'process raft request' (duration: 41.783566ms)","trace[370480367] 'compare' (duration: 70.265603ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:09.048815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.518942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:09.048877Z","caller":"traceutil/trace.go:171","msg":"trace[244907380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:193; }","duration":"110.607814ms","start":"2026-08-28T04:04:08.938255Z","end":"2026-08-28T04:04:09.048863Z","steps":["trace[244907380] 'agreement among raft nodes before linearized reading' (duration: 110.483282ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:09.232714Z","caller":"traceutil/trace.go:171","msg":"trace[1985640834] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"126.676051ms","start":"2026-08-28T04:04:09.106012Z","end":"2026-08-28T04:04:09.232688Z","steps":["trace[1985640834] 'process raft request' (duration: 73.89338ms)","trace[1985640834] 'compare' (duration: 52.594479ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:09.421244Z","caller":"traceutil/trace.go:171","msg":"trace[374695172] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"125.963893ms","start":"2026-08-28T04:04:09.295256Z","end":"2026-08-28T04:04:09.42122Z","steps":["trace[374695172] 'process raft request' (duration: 76.75758ms)","trace[374695172] 'compare' (duration: 48.870609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:09.725419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.218908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:09.725512Z","caller":"traceutil/trace.go:171","msg":"trace[606122992] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"247.750262ms","start":"2026-08-28T04:04:09.47774Z","end":"2026-08-28T04:04:09.725491Z","steps":["trace[606122992] 'process raft request' (duration: 58.281221ms)","trace[606122992] 'compare' (duration: 188.855844ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:10.05Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.749977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:10.050118Z","caller":"traceutil/trace.go:171","msg":"trace[2023350818] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"229.654182ms","start":"2026-08-28T04:04:09.820441Z","end":"2026-08-28T04:04:10.050095Z","steps":["trace[2023350818] 'process raft request' (duration: 127.759413ms)","trace[2023350818] 'compare' (duration: 101.642046ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:10.421713Z","caller":"traceutil/trace.go:171","msg":"trace[76928200] transaction","detail":"{read_only:false; response_revision:219; number_of_response:1; }","duration":"117.003606ms","start":"2026-08-28T04:04:10.304678Z","end":"2026-08-28T04:04:10.421682Z","steps":["trace[76928200] 'process raft request' (duration: 75.706862ms)","trace[76928200] 'compare' (duration: 41.120272ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:10.667281Z","caller":"traceutil/trace.go:171","msg":"trace[1776021086] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"143.188326ms","start":"2026-08-28T04:04:10.524075Z","end":"2026-08-28T04:04:10.667264Z","steps":["trace[1776021086] 'process raft request' (duration: 143.086965ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:10.691263Z","caller":"traceutil/trace.go:171","msg":"trace[2035929775] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"157.574191ms","start":"2026-08-28T04:04:10.533666Z","end":"2026-08-28T04:04:10.69124Z","steps":["trace[2035929775] 'process raft request' (duration: 157.41207ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:10.815654Z","caller":"traceutil/trace.go:171","msg":"trace[1474881381] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"121.301212ms","start":"2026-08-28T04:04:10.694332Z","end":"2026-08-28T04:04:10.815633Z","steps":["trace[1474881381] 'process raft request' (duration: 59.918543ms)","trace[1474881381] 'compare' (duration: 61.258857ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:11.053083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.587475ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:11.053178Z","caller":"traceutil/trace.go:171","msg":"trace[2009685813] transaction","detail":"{read_only:false; response_revision:230; number_of_response:1; }","duration":"148.206326ms","start":"2026-08-28T04:04:10.904956Z","end":"2026-08-28T04:04:11.053162Z","steps":["trace[2009685813] 'process raft request' (duration: 34.48356ms)","trace[2009685813] 'compare' (duration: 113.398973ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:11.261557Z","caller":"traceutil/trace.go:171","msg":"trace[974358526] linearizableReadLoop","detail":"{readStateIndex:237; appliedIndex:236; }","duration":"182.377031ms","start":"2026-08-28T04:04:11.079159Z","end":"2026-08-28T04:04:11.261536Z","steps":["trace[974358526] 'read index received' (duration: 132.234227ms)","trace[974358526] 'applied index is now lower than readState.Index' (duration: 50.141734ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:11.261671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.498972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:root-ca-cert-publisher\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:11.261656Z","caller":"traceutil/trace.go:171","msg":"trace[1795280145] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"198.783266ms","start":"2026-08-28T04:04:11.062842Z","end":"2026-08-28T04:04:11.261625Z","steps":["trace[1795280145] 'process raft request' (duration: 148.592752ms)","trace[1795280145] 'compare' (duration: 50.006152ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:11.261697Z","caller":"traceutil/trace.go:171","msg":"trace[809113084] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:root-ca-cert-publisher; range_end:; response_count:0; response_revision:232; }","duration":"182.544183ms","start":"2026-08-28T04:04:11.079143Z","end":"2026-08-28T04:04:11.261687Z","steps":["trace[809113084] 'agreement among raft nodes before linearized reading' (duration: 182.479142ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:11.565691Z","caller":"traceutil/trace.go:171","msg":"trace[783779336] linearizableReadLoop","detail":"{readStateIndex:241; appliedIndex:239; }","duration":"226.931105ms","start":"2026-08-28T04:04:11.338731Z","end":"2026-08-28T04:04:11.565662Z","steps":["trace[783779336] 'read index received' (duration: 10.821485ms)","trace[783779336] 'applied index is now lower than readState.Index' (duration: 216.10869ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:11.565802Z","caller":"traceutil/trace.go:171","msg":"trace[1556104595] transaction","detail":"{read_only:false; response_revision:236; number_of_response:1; }","duration":"230.916357ms","start":"2026-08-28T04:04:11.334858Z","end":"2026-08-28T04:04:11.565775Z","steps":["trace[1556104595] 'process raft request' (duration: 230.699085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:11.565862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.115577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:11.565893Z","caller":"traceutil/trace.go:171","msg":"trace[1523125008] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:236; }","duration":"227.179468ms","start":"2026-08-28T04:04:11.338705Z","end":"2026-08-28T04:04:11.565884Z","steps":["trace[1523125008] 'agreement among raft nodes before linearized reading' (duration: 227.099817ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:11.585579Z","caller":"traceutil/trace.go:171","msg":"trace[1808381767] transaction","detail":"{read_only:false; response_revision:237; number_of_response:1; }","duration":"232.241622ms","start":"2026-08-28T04:04:11.353305Z","end":"2026-08-28T04:04:11.585547Z","steps":["trace[1808381767] 'process raft request' (duration: 232.063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:11.585618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.091342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-28T04:04:11.58568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.235422ms","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-28T04:04:11.585722Z","caller":"traceutil/trace.go:171","msg":"trace[1223135115] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:237; }","duration":"233.299644ms","start":"2026-08-28T04:04:11.35241Z","end":"2026-08-28T04:04:11.58571Z","steps":["trace[1223135115] 'agreement among raft nodes before linearized reading' (duration: 233.231703ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:11.585834Z","caller":"traceutil/trace.go:171","msg":"trace[635844815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:237; }","duration":"111.204423ms","start":"2026-08-28T04:04:11.474467Z","end":"2026-08-28T04:04:11.585671Z","steps":["trace[635844815] 'agreement among raft nodes before linearized reading' (duration: 111.023181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:11.585622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.338802ms","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-28T04:04:11.585967Z","caller":"traceutil/trace.go:171","msg":"trace[123655681] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:237; }","duration":"138.698696ms","start":"2026-08-28T04:04:11.447253Z","end":"2026-08-28T04:04:11.585952Z","steps":["trace[123655681] 'agreement among raft nodes before linearized reading' (duration: 138.277372ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:11.819175Z","caller":"traceutil/trace.go:171","msg":"trace[1795033996] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"183.017898ms","start":"2026-08-28T04:04:11.636131Z","end":"2026-08-28T04:04:11.819149Z","steps":["trace[1795033996] 'process raft request' (duration: 87.508412ms)","trace[1795033996] 'compare' (duration: 95.384365ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:12.13961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.762441ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:12.139761Z","caller":"traceutil/trace.go:171","msg":"trace[1623053511] linearizableReadLoop","detail":"{readStateIndex:251; appliedIndex:250; }","duration":"101.087238ms","start":"2026-08-28T04:04:12.038661Z","end":"2026-08-28T04:04:12.139748Z","steps":["trace[1623053511] 'read index received' (duration: 37.581µs)","trace[1623053511] 'applied index is now lower than readState.Index' (duration: 101.048577ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:12.139852Z","caller":"traceutil/trace.go:171","msg":"trace[2001278399] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"219.441044ms","start":"2026-08-28T04:04:11.920386Z","end":"2026-08-28T04:04:12.139827Z","steps":["trace[2001278399] 'process raft request' (duration: 41.40791ms)","trace[2001278399] 'compare' (duration: 177.614799ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:12.139944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.255819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:12.140014Z","caller":"traceutil/trace.go:171","msg":"trace[1295520403] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:246; }","duration":"101.422241ms","start":"2026-08-28T04:04:12.038576Z","end":"2026-08-28T04:04:12.139998Z","steps":["trace[1295520403] 'agreement among raft nodes before linearized reading' (duration: 101.24701ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:12.310366Z","caller":"traceutil/trace.go:171","msg":"trace[1583884042] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"164.75392ms","start":"2026-08-28T04:04:12.14559Z","end":"2026-08-28T04:04:12.310344Z","steps":["trace[1583884042] 'process raft request' (duration: 129.474797ms)","trace[1583884042] 'compare' (duration: 35.144681ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:12.598501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.483464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:12.598584Z","caller":"traceutil/trace.go:171","msg":"trace[1778399753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:249; }","duration":"159.606855ms","start":"2026-08-28T04:04:12.43896Z","end":"2026-08-28T04:04:12.598567Z","steps":["trace[1778399753] 'range keys from in-memory index tree' (duration: 159.408593ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:12.598523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.56876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/kube-system/system:controller:bootstrap-signer\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:12.598677Z","caller":"traceutil/trace.go:171","msg":"trace[1168393172] range","detail":"{range_begin:/registry/rolebindings/kube-system/system:controller:bootstrap-signer; range_end:; response_count:0; response_revision:249; }","duration":"186.783082ms","start":"2026-08-28T04:04:12.411875Z","end":"2026-08-28T04:04:12.598658Z","steps":["trace[1168393172] 'range keys from in-memory index tree' (duration: 186.460969ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:13.229428Z","caller":"traceutil/trace.go:171","msg":"trace[1235886502] linearizableReadLoop","detail":"{readStateIndex:256; appliedIndex:255; }","duration":"190.241783ms","start":"2026-08-28T04:04:13.039164Z","end":"2026-08-28T04:04:13.229405Z","steps":["trace[1235886502] 'read index received' (duration: 190.097812ms)","trace[1235886502] 'applied index is now lower than readState.Index' (duration: 143.021µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:13.229451Z","caller":"traceutil/trace.go:171","msg":"trace[69772007] transaction","detail":"{read_only:false; response_revision:251; number_of_response:1; }","duration":"544.320408ms","start":"2026-08-28T04:04:12.685107Z","end":"2026-08-28T04:04:13.229427Z","steps":["trace[69772007] 'process raft request' (duration: 544.173687ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.229581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.401585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:13.22962Z","caller":"traceutil/trace.go:171","msg":"trace[2052655837] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:251; }","duration":"190.483005ms","start":"2026-08-28T04:04:13.039128Z","end":"2026-08-28T04:04:13.229611Z","steps":["trace[2052655837] 'agreement among raft nodes before linearized reading' (duration: 190.383604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.229717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:12.685081Z","time spent":"544.547481ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":718,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:13.582641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.732956ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:13.582746Z","caller":"traceutil/trace.go:171","msg":"trace[734752479] linearizableReadLoop","detail":"{readStateIndex:257; appliedIndex:256; }","duration":"143.794696ms","start":"2026-08-28T04:04:13.438922Z","end":"2026-08-28T04:04:13.582716Z","steps":["trace[734752479] 'read index received' (duration: 29.33µs)","trace[734752479] 'applied index is now lower than readState.Index' (duration: 143.764006ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:13.582913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.972499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:13.582825Z","caller":"traceutil/trace.go:171","msg":"trace[1666975746] transaction","detail":"{read_only:false; response_revision:252; number_of_response:1; }","duration":"347.725263ms","start":"2026-08-28T04:04:13.23507Z","end":"2026-08-28T04:04:13.582795Z","steps":["trace[1666975746] 'process raft request' (duration: 162.767686ms)","trace[1666975746] 'compare' (duration: 184.496322ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:13.582981Z","caller":"traceutil/trace.go:171","msg":"trace[741174869] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:252; }","duration":"144.08204ms","start":"2026-08-28T04:04:13.438884Z","end":"2026-08-28T04:04:13.582966Z","steps":["trace[741174869] 'agreement among raft nodes before linearized reading' (duration: 143.918598ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.582945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.25664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"warn","ts":"2026-08-28T04:04:13.58289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.479278ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:13.583074Z","caller":"traceutil/trace.go:171","msg":"trace[128467817] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:252; }","duration":"100.427512ms","start":"2026-08-28T04:04:13.482633Z","end":"2026-08-28T04:04:13.58306Z","steps":["trace[128467817] 'agreement among raft nodes before linearized reading' (duration: 100.22921ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:13.583111Z","caller":"traceutil/trace.go:171","msg":"trace[1268772415] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:252; }","duration":"132.69855ms","start":"2026-08-28T04:04:13.450398Z","end":"2026-08-28T04:04:13.583097Z","steps":["trace[1268772415] 'agreement among raft nodes before linearized reading' (duration: 132.431767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.583679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.235053Z","time spent":"348.567432ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":714,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:13.919904Z","caller":"traceutil/trace.go:171","msg":"trace[83477456] linearizableReadLoop","detail":"{readStateIndex:259; appliedIndex:257; }","duration":"308.845805ms","start":"2026-08-28T04:04:13.611039Z","end":"2026-08-28T04:04:13.919885Z","steps":["trace[83477456] 'read index received' (duration: 218.452758ms)","trace[83477456] 'applied index is now lower than readState.Index' (duration: 90.391817ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:13.920017Z","caller":"traceutil/trace.go:171","msg":"trace[613919002] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"327.512191ms","start":"2026-08-28T04:04:13.592497Z","end":"2026-08-28T04:04:13.920009Z","steps":["trace[613919002] 'process raft request' (duration: 327.305948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.920139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.592455Z","time spent":"327.617722ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:13.920152Z","caller":"traceutil/trace.go:171","msg":"trace[522276376] transaction","detail":"{read_only:false; response_revision:253; number_of_response:1; }","duration":"327.641212ms","start":"2026-08-28T04:04:13.592489Z","end":"2026-08-28T04:04:13.92013Z","steps":["trace[522276376] 'process raft request' (duration: 236.866721ms)","trace[522276376] 'compare' (duration: 90.246835ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:13.92017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.122508ms","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-28T04:04:13.92016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.34859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-28T04:04:13.920199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.463671ms","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-28T04:04:13.920234Z","caller":"traceutil/trace.go:171","msg":"trace[415336098] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:254; }","duration":"309.22444ms","start":"2026-08-28T04:04:13.610994Z","end":"2026-08-28T04:04:13.920219Z","steps":["trace[415336098] 'agreement among raft nodes before linearized reading' (duration: 309.101669ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:13.920241Z","caller":"traceutil/trace.go:171","msg":"trace[229599184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:254; }","duration":"286.457811ms","start":"2026-08-28T04:04:13.633769Z","end":"2026-08-28T04:04:13.920227Z","steps":["trace[229599184] 'agreement among raft nodes before linearized reading' (duration: 286.32942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.920247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.592444Z","time spent":"327.756714ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":726,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:13.920261Z","caller":"traceutil/trace.go:171","msg":"trace[1829036171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:254; }","duration":"286.573562ms","start":"2026-08-28T04:04:13.633673Z","end":"2026-08-28T04:04:13.920247Z","steps":["trace[1829036171] 'agreement among raft nodes before linearized reading' (duration: 286.418501ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:13.920263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.610977Z","time spent":"309.27994ms","remote":"127.0.0.1:52368","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-28T04:04:14.229635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.401801ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:14.22983Z","caller":"traceutil/trace.go:171","msg":"trace[1756847678] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"306.142809ms","start":"2026-08-28T04:04:13.923671Z","end":"2026-08-28T04:04:14.229814Z","steps":["trace[1756847678] 'process raft request' (duration: 306.049088ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:14.229832Z","caller":"traceutil/trace.go:171","msg":"trace[1027028624] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"306.593894ms","start":"2026-08-28T04:04:13.923206Z","end":"2026-08-28T04:04:14.229799Z","steps":["trace[1027028624] 'process raft request' (duration: 187.94596ms)","trace[1027028624] 'compare' (duration: 118.25013ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:14.229886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.923666Z","time spent":"306.190699ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:04:14.229928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.923188Z","time spent":"306.684745ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:14.229936Z","caller":"traceutil/trace.go:171","msg":"trace[1193177973] linearizableReadLoop","detail":"{readStateIndex:263; appliedIndex:260; }","duration":"289.061ms","start":"2026-08-28T04:04:13.940851Z","end":"2026-08-28T04:04:14.229912Z","steps":["trace[1193177973] 'read index received' (duration: 55.498211ms)","trace[1193177973] 'applied index is now lower than readState.Index' (duration: 233.560939ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:14.229971Z","caller":"traceutil/trace.go:171","msg":"trace[1255887934] transaction","detail":"{read_only:false; response_revision:258; number_of_response:1; }","duration":"303.937386ms","start":"2026-08-28T04:04:13.926022Z","end":"2026-08-28T04:04:14.229959Z","steps":["trace[1255887934] 'process raft request' (duration: 303.750125ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:14.230062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.200081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:113"} {"level":"info","ts":"2026-08-28T04:04:14.230091Z","caller":"traceutil/trace.go:171","msg":"trace[370017388] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:258; }","duration":"289.273903ms","start":"2026-08-28T04:04:13.94081Z","end":"2026-08-28T04:04:14.230084Z","steps":["trace[370017388] 'agreement among raft nodes before linearized reading' (duration: 289.170512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:14.230023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:13.92601Z","time spent":"303.985037ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:14.231583Z","caller":"traceutil/trace.go:171","msg":"trace[646947195] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"199.303352ms","start":"2026-08-28T04:04:14.032265Z","end":"2026-08-28T04:04:14.231569Z","steps":["trace[646947195] 'process raft request' (duration: 199.248642ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:14.617425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.114704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:14.617602Z","caller":"traceutil/trace.go:171","msg":"trace[481392300] transaction","detail":"{read_only:false; response_revision:260; number_of_response:1; }","duration":"386.073664ms","start":"2026-08-28T04:04:14.231501Z","end":"2026-08-28T04:04:14.617575Z","steps":["trace[481392300] 'process raft request' (duration: 98.225802ms)","trace[481392300] 'compare' (duration: 286.938563ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:14.617647Z","caller":"traceutil/trace.go:171","msg":"trace[470171365] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"383.464357ms","start":"2026-08-28T04:04:14.234162Z","end":"2026-08-28T04:04:14.617626Z","steps":["trace[470171365] 'process raft request' (duration: 383.391806ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:14.617716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.231477Z","time spent":"386.181665ms","remote":"127.0.0.1:52148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":101,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:04:14.617724Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.234143Z","time spent":"383.541087ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:14.617728Z","caller":"traceutil/trace.go:171","msg":"trace[850508909] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"385.289485ms","start":"2026-08-28T04:04:14.232417Z","end":"2026-08-28T04:04:14.617706Z","steps":["trace[850508909] 'read index received' (duration: 97.307512ms)","trace[850508909] 'applied index is now lower than readState.Index' (duration: 287.978653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:14.61799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.112445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:04:14.617998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.569718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5335"} {"level":"info","ts":"2026-08-28T04:04:14.618042Z","caller":"traceutil/trace.go:171","msg":"trace[2004232993] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:261; }","duration":"263.199466ms","start":"2026-08-28T04:04:14.354827Z","end":"2026-08-28T04:04:14.618027Z","steps":["trace[2004232993] 'agreement among raft nodes before linearized reading' (duration: 263.080534ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:14.618071Z","caller":"traceutil/trace.go:171","msg":"trace[1467026237] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:261; }","duration":"385.666719ms","start":"2026-08-28T04:04:14.232389Z","end":"2026-08-28T04:04:14.618056Z","steps":["trace[1467026237] 'agreement among raft nodes before linearized reading' (duration: 385.495927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:14.618111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.232365Z","time spent":"385.7347ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5358,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-08-28T04:04:14.837707Z","caller":"traceutil/trace.go:171","msg":"trace[1691996667] transaction","detail":"{read_only:false; response_revision:262; number_of_response:1; }","duration":"218.099917ms","start":"2026-08-28T04:04:14.619576Z","end":"2026-08-28T04:04:14.837676Z","steps":["trace[1691996667] 'process raft request' (duration: 167.4144ms)","trace[1691996667] 'compare' (duration: 50.514675ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:15.003105Z","caller":"traceutil/trace.go:171","msg":"trace[180656508] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:266; }","duration":"374.452133ms","start":"2026-08-28T04:04:14.62863Z","end":"2026-08-28T04:04:15.003082Z","steps":["trace[180656508] 'read index received' (duration: 158.333266ms)","trace[180656508] 'applied index is now lower than readState.Index' (duration: 216.117797ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:15.003407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.775406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:15.003465Z","caller":"traceutil/trace.go:171","msg":"trace[1481205071] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:0; response_revision:264; }","duration":"374.869527ms","start":"2026-08-28T04:04:14.628585Z","end":"2026-08-28T04:04:15.003455Z","steps":["trace[1481205071] 'agreement among raft nodes before linearized reading' (duration: 374.780406ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:15.00349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.628568Z","time spent":"374.916448ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"info","ts":"2026-08-28T04:04:15.003736Z","caller":"traceutil/trace.go:171","msg":"trace[539044462] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"376.787247ms","start":"2026-08-28T04:04:14.626925Z","end":"2026-08-28T04:04:15.003712Z","steps":["trace[539044462] 'process raft request' (duration: 376.08886ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:15.003144Z","caller":"traceutil/trace.go:171","msg":"trace[467581277] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"379.879629ms","start":"2026-08-28T04:04:14.623235Z","end":"2026-08-28T04:04:15.003115Z","steps":["trace[467581277] 'process raft request' (duration: 319.613053ms)","trace[467581277] 'compare' (duration: 60.010133ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:15.003988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.623222Z","time spent":"380.730008ms","remote":"127.0.0.1:52458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:15.004128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.119976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.119\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-28T04:04:15.004138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:14.626905Z","time spent":"376.976319ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5149,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:15.004155Z","caller":"traceutil/trace.go:171","msg":"trace[603560492] range","detail":"{range_begin:/registry/masterleases/162.253.55.119; range_end:; response_count:0; response_revision:264; }","duration":"164.183587ms","start":"2026-08-28T04:04:14.839963Z","end":"2026-08-28T04:04:15.004147Z","steps":["trace[603560492] 'agreement among raft nodes before linearized reading' (duration: 163.960704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:15.375949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.279954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:15.376132Z","caller":"traceutil/trace.go:171","msg":"trace[896583108] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"284.025209ms","start":"2026-08-28T04:04:15.092091Z","end":"2026-08-28T04:04:15.376116Z","steps":["trace[896583108] 'read index received' (duration: 87.544953ms)","trace[896583108] 'applied index is now lower than readState.Index' (duration: 196.478856ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:15.376154Z","caller":"traceutil/trace.go:171","msg":"trace[1892320185] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"280.944898ms","start":"2026-08-28T04:04:15.095196Z","end":"2026-08-28T04:04:15.376141Z","steps":["trace[1892320185] 'process raft request' (duration: 280.907707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:15.376228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.750303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6964"} {"level":"info","ts":"2026-08-28T04:04:15.37625Z","caller":"traceutil/trace.go:171","msg":"trace[1470649216] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:267; }","duration":"368.816305ms","start":"2026-08-28T04:04:15.007426Z","end":"2026-08-28T04:04:15.376243Z","steps":["trace[1470649216] 'agreement among raft nodes before linearized reading' (duration: 368.732824ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:15.376267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.007414Z","time spent":"368.848015ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6987,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-08-28T04:04:15.376285Z","caller":"traceutil/trace.go:171","msg":"trace[1423494009] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"283.231411ms","start":"2026-08-28T04:04:15.092959Z","end":"2026-08-28T04:04:15.376191Z","steps":["trace[1423494009] 'process raft request' (duration: 283.10409ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:15.3766Z","caller":"traceutil/trace.go:171","msg":"trace[2129047728] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"368.136117ms","start":"2026-08-28T04:04:15.008166Z","end":"2026-08-28T04:04:15.376302Z","steps":["trace[2129047728] 'process raft request' (duration: 171.445389ms)","trace[2129047728] 'compare' (duration: 196.115702ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:15.376924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.008145Z","time spent":"368.506841ms","remote":"127.0.0.1:52194","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:15.744426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.102444ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:15.74474Z","caller":"traceutil/trace.go:171","msg":"trace[353715903] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"294.949534ms","start":"2026-08-28T04:04:15.449753Z","end":"2026-08-28T04:04:15.744703Z","steps":["trace[353715903] 'process raft request' (duration: 294.919433ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:15.744783Z","caller":"traceutil/trace.go:171","msg":"trace[670278825] linearizableReadLoop","detail":"{readStateIndex:276; appliedIndex:274; }","duration":"296.417699ms","start":"2026-08-28T04:04:15.448325Z","end":"2026-08-28T04:04:15.744742Z","steps":["trace[670278825] 'read index received' (duration: 192.926011ms)","trace[670278825] 'applied index is now lower than readState.Index' (duration: 103.488058ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:15.744841Z","caller":"traceutil/trace.go:171","msg":"trace[1644747310] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"358.336808ms","start":"2026-08-28T04:04:15.386478Z","end":"2026-08-28T04:04:15.744815Z","steps":["trace[1644747310] 'process raft request' (duration: 358.116485ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:15.745001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.647321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"warn","ts":"2026-08-28T04:04:15.745012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.386457Z","time spent":"358.469079ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6740,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:15.74505Z","caller":"traceutil/trace.go:171","msg":"trace[1500541430] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:271; }","duration":"296.742812ms","start":"2026-08-28T04:04:15.448293Z","end":"2026-08-28T04:04:15.745036Z","steps":["trace[1500541430] 'agreement among raft nodes before linearized reading' (duration: 296.58819ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:15.745453Z","caller":"traceutil/trace.go:171","msg":"trace[2086285427] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"361.333888ms","start":"2026-08-28T04:04:15.383414Z","end":"2026-08-28T04:04:15.744748Z","steps":["trace[2086285427] 'process raft request' (duration: 257.84672ms)","trace[2086285427] 'compare' (duration: 102.937512ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:15.745557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.383398Z","time spent":"362.097956ms","remote":"127.0.0.1:52268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":407,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:16.242296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.243813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:16.242469Z","caller":"traceutil/trace.go:171","msg":"trace[1202255416] linearizableReadLoop","detail":"{readStateIndex:279; appliedIndex:278; }","duration":"449.282667ms","start":"2026-08-28T04:04:15.793171Z","end":"2026-08-28T04:04:16.242454Z","steps":["trace[1202255416] 'read index received' (duration: 169.729891ms)","trace[1202255416] 'applied index is now lower than readState.Index' (duration: 279.551526ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:16.242517Z","caller":"traceutil/trace.go:171","msg":"trace[1924731430] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"489.29055ms","start":"2026-08-28T04:04:15.753179Z","end":"2026-08-28T04:04:16.24247Z","steps":["trace[1924731430] 'process raft request' (duration: 209.814345ms)","trace[1924731430] 'compare' (duration: 279.088221ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:16.24264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.753167Z","time spent":"489.417251ms","remote":"127.0.0.1:52382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":466,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:16.242728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.525789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:16.242853Z","caller":"traceutil/trace.go:171","msg":"trace[2097853492] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ephemeral-volume-controller; range_end:; response_count:0; response_revision:273; }","duration":"449.708692ms","start":"2026-08-28T04:04:15.793111Z","end":"2026-08-28T04:04:16.242819Z","steps":["trace[2097853492] 'agreement among raft nodes before linearized reading' (duration: 449.448369ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:16.242914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:15.793086Z","time spent":"449.812943ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/ephemeral-volume-controller\" "} {"level":"warn","ts":"2026-08-28T04:04:16.243203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.012419ms","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-28T04:04:16.243276Z","caller":"traceutil/trace.go:171","msg":"trace[642464416] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:273; }","duration":"244.140641ms","start":"2026-08-28T04:04:15.999124Z","end":"2026-08-28T04:04:16.243265Z","steps":["trace[642464416] 'agreement among raft nodes before linearized reading' (duration: 243.496995ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:16.541368Z","caller":"traceutil/trace.go:171","msg":"trace[1030840787] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"213.052282ms","start":"2026-08-28T04:04:16.328264Z","end":"2026-08-28T04:04:16.541316Z","steps":["trace[1030840787] 'process raft request' (duration: 118.764636ms)","trace[1030840787] 'compare' (duration: 93.994373ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:16.541439Z","caller":"traceutil/trace.go:171","msg":"trace[702265968] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"212.660298ms","start":"2026-08-28T04:04:16.328748Z","end":"2026-08-28T04:04:16.541408Z","steps":["trace[702265968] 'process raft request' (duration: 212.414226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:16.846094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.744922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:16.846177Z","caller":"traceutil/trace.go:171","msg":"trace[1253450808] linearizableReadLoop","detail":"{readStateIndex:287; appliedIndex:286; }","duration":"230.662623ms","start":"2026-08-28T04:04:16.615502Z","end":"2026-08-28T04:04:16.846165Z","steps":["trace[1253450808] 'read index received' (duration: 129.85414ms)","trace[1253450808] 'applied index is now lower than readState.Index' (duration: 100.807573ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:16.846252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.755184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-nknnn4\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-08-28T04:04:16.84628Z","caller":"traceutil/trace.go:171","msg":"trace[1222514379] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-nknnn4; range_end:; response_count:1; response_revision:281; }","duration":"230.819644ms","start":"2026-08-28T04:04:16.615451Z","end":"2026-08-28T04:04:16.846271Z","steps":["trace[1222514379] 'agreement among raft nodes before linearized reading' (duration: 230.745533ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:16.846304Z","caller":"traceutil/trace.go:171","msg":"trace[1155358203] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"295.744149ms","start":"2026-08-28T04:04:16.550491Z","end":"2026-08-28T04:04:16.846235Z","steps":["trace[1155358203] 'process raft request' (duration: 194.805875ms)","trace[1155358203] 'compare' (duration: 100.611601ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:17.599348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.163495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:17.59944Z","caller":"traceutil/trace.go:171","msg":"trace[45631912] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:287; }","duration":"746.340111ms","start":"2026-08-28T04:04:16.853089Z","end":"2026-08-28T04:04:17.599429Z","steps":["trace[45631912] 'read index received' (duration: 140.919123ms)","trace[45631912] 'applied index is now lower than readState.Index' (duration: 605.420088ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:17.599506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"746.410932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-28T04:04:17.599519Z","caller":"traceutil/trace.go:171","msg":"trace[1982679135] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:282; }","duration":"746.451013ms","start":"2026-08-28T04:04:16.853062Z","end":"2026-08-28T04:04:17.599513Z","steps":["trace[1982679135] 'agreement among raft nodes before linearized reading' (duration: 746.393363ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:17.599538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:16.853051Z","time spent":"746.483133ms","remote":"127.0.0.1:52210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-08-28T04:04:17.599553Z","caller":"traceutil/trace.go:171","msg":"trace[162148427] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"748.358093ms","start":"2026-08-28T04:04:16.851116Z","end":"2026-08-28T04:04:17.599474Z","steps":["trace[162148427] 'process raft request' (duration: 143.031276ms)","trace[162148427] 'compare' (duration: 605.035804ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:17.599738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:16.8511Z","time spent":"748.573535ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11436,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:17.802186Z","caller":"traceutil/trace.go:171","msg":"trace[1131893307] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"198.881113ms","start":"2026-08-28T04:04:17.603269Z","end":"2026-08-28T04:04:17.80215Z","steps":["trace[1131893307] 'process raft request' (duration: 142.56107ms)","trace[1131893307] 'compare' (duration: 56.137191ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:18.122649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.091453ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:18.122762Z","caller":"traceutil/trace.go:171","msg":"trace[537046147] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"519.110671ms","start":"2026-08-28T04:04:17.603637Z","end":"2026-08-28T04:04:18.122748Z","steps":["trace[537046147] 'process raft request' (duration: 396.863736ms)","trace[537046147] 'compare' (duration: 121.959782ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:18.122816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:17.60363Z","time spent":"519.166501ms","remote":"127.0.0.1:52452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":324,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-28T04:04:18.122849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.984385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:18.122757Z","caller":"traceutil/trace.go:171","msg":"trace[1104102363] linearizableReadLoop","detail":"{readStateIndex:290; appliedIndex:289; }","duration":"316.880264ms","start":"2026-08-28T04:04:17.805857Z","end":"2026-08-28T04:04:18.122737Z","steps":["trace[1104102363] 'read index received' (duration: 194.589599ms)","trace[1104102363] 'applied index is now lower than readState.Index' (duration: 122.289375ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:04:18.122926Z","caller":"traceutil/trace.go:171","msg":"trace[1781410038] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cronjob-controller; range_end:; response_count:0; response_revision:284; }","duration":"317.094686ms","start":"2026-08-28T04:04:17.805818Z","end":"2026-08-28T04:04:18.122913Z","steps":["trace[1781410038] 'agreement among raft nodes before linearized reading' (duration: 316.965295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:18.122992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:17.805802Z","time spent":"317.179237ms","remote":"127.0.0.1:52306","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/cronjob-controller\" "} {"level":"info","ts":"2026-08-28T04:04:23.626768Z","caller":"traceutil/trace.go:171","msg":"trace[67770222] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"313.594256ms","start":"2026-08-28T04:04:23.313151Z","end":"2026-08-28T04:04:23.626745Z","steps":["trace[67770222] 'process raft request' (duration: 313.476385ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:23.626901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:23.313133Z","time spent":"313.706947ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4467,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:50.566148Z","caller":"traceutil/trace.go:171","msg":"trace[1173673369] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"124.687356ms","start":"2026-08-28T04:04:50.44143Z","end":"2026-08-28T04:04:50.566117Z","steps":["trace[1173673369] 'process raft request' (duration: 118.945904ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:50.566677Z","caller":"traceutil/trace.go:171","msg":"trace[827290963] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"115.74382ms","start":"2026-08-28T04:04:50.450917Z","end":"2026-08-28T04:04:50.56666Z","steps":["trace[827290963] 'process raft request' (duration: 115.50701ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:56.516657Z","caller":"traceutil/trace.go:171","msg":"trace[308198791] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"131.660476ms","start":"2026-08-28T04:04:56.384976Z","end":"2026-08-28T04:04:56.516637Z","steps":["trace[308198791] 'read index received' (duration: 131.231169ms)","trace[308198791] 'applied index is now lower than readState.Index' (duration: 428.387µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:56.516855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.858263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gl522\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-08-28T04:04:56.516887Z","caller":"traceutil/trace.go:171","msg":"trace[945223594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gl522; range_end:; response_count:1; response_revision:640; }","duration":"131.933686ms","start":"2026-08-28T04:04:56.384943Z","end":"2026-08-28T04:04:56.516876Z","steps":["trace[945223594] 'agreement among raft nodes before linearized reading' (duration: 131.78359ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:56.517159Z","caller":"traceutil/trace.go:171","msg":"trace[644998802] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"443.641643ms","start":"2026-08-28T04:04:56.073066Z","end":"2026-08-28T04:04:56.516708Z","steps":["trace[644998802] 'process raft request' (duration: 443.200076ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:56.517397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:56.073036Z","time spent":"444.248797ms","remote":"127.0.0.1:52184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-28T04:04:56.638799Z","caller":"traceutil/trace.go:171","msg":"trace[1560652164] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"108.813976ms","start":"2026-08-28T04:04:56.529959Z","end":"2026-08-28T04:04:56.638773Z","steps":["trace[1560652164] 'process raft request' (duration: 28.078896ms)","trace[1560652164] 'compare' (duration: 80.372136ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:57.166267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.8534ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:04:57.166409Z","caller":"traceutil/trace.go:171","msg":"trace[1177267035] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"522.795012ms","start":"2026-08-28T04:04:56.643588Z","end":"2026-08-28T04:04:57.166383Z","steps":["trace[1177267035] 'process raft request' (duration: 318.725743ms)","trace[1177267035] 'compare' (duration: 203.545359ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:57.166502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:56.643559Z","time spent":"522.899676ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:57.168506Z","caller":"traceutil/trace.go:171","msg":"trace[1776069935] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"522.997059ms","start":"2026-08-28T04:04:56.645488Z","end":"2026-08-28T04:04:57.168485Z","steps":["trace[1776069935] 'process raft request' (duration: 522.954267ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:57.168525Z","caller":"traceutil/trace.go:171","msg":"trace[1934636008] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"524.697606ms","start":"2026-08-28T04:04:56.643805Z","end":"2026-08-28T04:04:57.168503Z","steps":["trace[1934636008] 'process raft request' (duration: 524.520079ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:57.168672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:56.643782Z","time spent":"524.79703ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:04:57.168879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:56.645475Z","time spent":"523.102533ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:04:57.582367Z","caller":"traceutil/trace.go:171","msg":"trace[1921365743] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"161.463641ms","start":"2026-08-28T04:04:57.420872Z","end":"2026-08-28T04:04:57.582335Z","steps":["trace[1921365743] 'process raft request' (duration: 73.821906ms)","trace[1921365743] 'compare' (duration: 87.51404ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:06:34.840134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.358067ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:06:34.840236Z","caller":"traceutil/trace.go:171","msg":"trace[1709271719] linearizableReadLoop","detail":"{readStateIndex:2002; appliedIndex:2001; }","duration":"311.458706ms","start":"2026-08-28T04:06:34.52876Z","end":"2026-08-28T04:06:34.840218Z","steps":["trace[1709271719] 'read index received' (duration: 93.972µs)","trace[1709271719] 'applied index is now lower than readState.Index' (duration: 311.363424ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:06:34.840332Z","caller":"traceutil/trace.go:171","msg":"trace[237769503] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"393.59153ms","start":"2026-08-28T04:06:34.446724Z","end":"2026-08-28T04:06:34.840316Z","steps":["trace[237769503] 'process raft request' (duration: 10.887847ms)","trace[237769503] 'compare' (duration: 382.243854ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:06:34.840412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:06:34.446694Z","time spent":"393.677922ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:06:34.840743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.981145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cfa06f04-a6b7-4d5e-bcaf-5c490ab34efe\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-28T04:06:34.840782Z","caller":"traceutil/trace.go:171","msg":"trace[985302882] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-cfa06f04-a6b7-4d5e-bcaf-5c490ab34efe; range_end:; response_count:1; response_revision:1927; }","duration":"312.063887ms","start":"2026-08-28T04:06:34.528708Z","end":"2026-08-28T04:06:34.840772Z","steps":["trace[985302882] 'agreement among raft nodes before linearized reading' (duration: 311.926514ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:06:34.840812Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:06:34.528674Z","time spent":"312.129987ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-cfa06f04-a6b7-4d5e-bcaf-5c490ab34efe\" "} {"level":"warn","ts":"2026-08-28T04:06:35.333552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.313384ms","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-28T04:06:35.333714Z","caller":"traceutil/trace.go:171","msg":"trace[1488328244] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1929; }","duration":"248.497346ms","start":"2026-08-28T04:06:35.085165Z","end":"2026-08-28T04:06:35.333662Z","steps":["trace[1488328244] 'range keys from in-memory index tree' (duration: 248.098829ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:06:47.38477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.079835ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:06:47.384892Z","caller":"traceutil/trace.go:171","msg":"trace[1148569614] transaction","detail":"{read_only:false; response_revision:2040; number_of_response:1; }","duration":"183.210843ms","start":"2026-08-28T04:06:47.201658Z","end":"2026-08-28T04:06:47.384868Z","steps":["trace[1148569614] 'process raft request' (duration: 20.912265ms)","trace[1148569614] 'compare' (duration: 161.396573ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:06:50.40226Z","caller":"traceutil/trace.go:171","msg":"trace[239978526] linearizableReadLoop","detail":"{readStateIndex:2138; appliedIndex:2137; }","duration":"112.05517ms","start":"2026-08-28T04:06:50.290176Z","end":"2026-08-28T04:06:50.402232Z","steps":["trace[239978526] 'read index received' (duration: 111.884517ms)","trace[239978526] 'applied index is now lower than readState.Index' (duration: 169.162µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:06:50.402416Z","caller":"traceutil/trace.go:171","msg":"trace[316894446] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"117.363118ms","start":"2026-08-28T04:06:50.284979Z","end":"2026-08-28T04:06:50.402342Z","steps":["trace[316894446] 'process raft request' (duration: 117.089854ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:06:50.402572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.379225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-28T04:06:50.40262Z","caller":"traceutil/trace.go:171","msg":"trace[328947759] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2059; }","duration":"112.486037ms","start":"2026-08-28T04:06:50.290119Z","end":"2026-08-28T04:06:50.402605Z","steps":["trace[328947759] 'agreement among raft nodes before linearized reading' (duration: 112.242693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:14.043473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.780095ms","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-28T04:07:14.043571Z","caller":"traceutil/trace.go:171","msg":"trace[680519101] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2189; }","duration":"147.940588ms","start":"2026-08-28T04:07:13.89561Z","end":"2026-08-28T04:07:14.043551Z","steps":["trace[680519101] 'range keys from in-memory index tree' (duration: 147.592462ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:14.660786Z","caller":"traceutil/trace.go:171","msg":"trace[315018837] linearizableReadLoop","detail":"{readStateIndex:2279; appliedIndex:2278; }","duration":"138.249543ms","start":"2026-08-28T04:07:14.522516Z","end":"2026-08-28T04:07:14.660765Z","steps":["trace[315018837] 'read index received' (duration: 138.04472ms)","trace[315018837] 'applied index is now lower than readState.Index' (duration: 203.963µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:07:14.661068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.536488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-28T04:07:14.661116Z","caller":"traceutil/trace.go:171","msg":"trace[1816337237] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2194; }","duration":"138.643719ms","start":"2026-08-28T04:07:14.52246Z","end":"2026-08-28T04:07:14.661104Z","steps":["trace[1816337237] 'agreement among raft nodes before linearized reading' (duration: 138.482067ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:14.660857Z","caller":"traceutil/trace.go:171","msg":"trace[1904576774] transaction","detail":"{read_only:false; response_revision:2194; number_of_response:1; }","duration":"154.894109ms","start":"2026-08-28T04:07:14.505913Z","end":"2026-08-28T04:07:14.660807Z","steps":["trace[1904576774] 'process raft request' (duration: 154.716436ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:14.966475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.733093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:07:14.966603Z","caller":"traceutil/trace.go:171","msg":"trace[2028993495] linearizableReadLoop","detail":"{readStateIndex:2280; appliedIndex:2279; }","duration":"205.365844ms","start":"2026-08-28T04:07:14.761216Z","end":"2026-08-28T04:07:14.966582Z","steps":["trace[2028993495] 'read index received' (duration: 45.429468ms)","trace[2028993495] 'applied index is now lower than readState.Index' (duration: 159.934996ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:07:14.966879Z","caller":"traceutil/trace.go:171","msg":"trace[1544235336] transaction","detail":"{read_only:false; response_revision:2195; number_of_response:1; }","duration":"299.136065ms","start":"2026-08-28T04:07:14.667692Z","end":"2026-08-28T04:07:14.966828Z","steps":["trace[1544235336] 'process raft request' (duration: 138.938894ms)","trace[1544235336] 'compare' (duration: 159.442609ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:07:14.966897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.651828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:07:14.966982Z","caller":"traceutil/trace.go:171","msg":"trace[388795538] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2195; }","duration":"205.790241ms","start":"2026-08-28T04:07:14.761171Z","end":"2026-08-28T04:07:14.966961Z","steps":["trace[388795538] 'agreement among raft nodes before linearized reading' (duration: 205.637188ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:14.971368Z","caller":"traceutil/trace.go:171","msg":"trace[589114973] transaction","detail":"{read_only:false; response_revision:2196; number_of_response:1; }","duration":"112.872693ms","start":"2026-08-28T04:07:14.858472Z","end":"2026-08-28T04:07:14.971345Z","steps":["trace[589114973] 'process raft request' (duration: 112.566508ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:07:14.97166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.507048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18cfdc8016655a62\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-08-28T04:07:14.971706Z","caller":"traceutil/trace.go:171","msg":"trace[229216056] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18cfdc8016655a62; range_end:; response_count:1; response_revision:2196; }","duration":"181.593699ms","start":"2026-08-28T04:07:14.790096Z","end":"2026-08-28T04:07:14.97169Z","steps":["trace[229216056] 'agreement among raft nodes before linearized reading' (duration: 181.457377ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:07:15.188306Z","caller":"traceutil/trace.go:171","msg":"trace[1795119702] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"213.793765ms","start":"2026-08-28T04:07:14.974488Z","end":"2026-08-28T04:07:15.188281Z","steps":["trace[1795119702] 'process raft request' (duration: 213.633593ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:44.799663Z","caller":"traceutil/trace.go:171","msg":"trace[1548364078] transaction","detail":"{read_only:false; response_revision:2789; number_of_response:1; }","duration":"113.155394ms","start":"2026-08-28T04:08:44.686479Z","end":"2026-08-28T04:08:44.799634Z","steps":["trace[1548364078] 'process raft request' (duration: 112.933251ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:44.904291Z","caller":"traceutil/trace.go:171","msg":"trace[1057519513] linearizableReadLoop","detail":"{readStateIndex:2897; appliedIndex:2895; }","duration":"142.639177ms","start":"2026-08-28T04:08:44.761626Z","end":"2026-08-28T04:08:44.904265Z","steps":["trace[1057519513] 'read index received' (duration: 37.972384ms)","trace[1057519513] 'applied index is now lower than readState.Index' (duration: 104.665293ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:08:44.904397Z","caller":"traceutil/trace.go:171","msg":"trace[1554263806] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"214.655686ms","start":"2026-08-28T04:08:44.689681Z","end":"2026-08-28T04:08:44.904337Z","steps":["trace[1554263806] 'process raft request' (duration: 212.702011ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:08:44.904558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.770629ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:08:44.904601Z","caller":"traceutil/trace.go:171","msg":"trace[1512089817] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2790; }","duration":"143.016462ms","start":"2026-08-28T04:08:44.761574Z","end":"2026-08-28T04:08:44.90459Z","steps":["trace[1512089817] 'agreement among raft nodes before linearized reading' (duration: 142.791159ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:08:50.293348Z","caller":"traceutil/trace.go:171","msg":"trace[685147794] transaction","detail":"{read_only:false; response_revision:2817; number_of_response:1; }","duration":"135.11214ms","start":"2026-08-28T04:08:50.158206Z","end":"2026-08-28T04:08:50.293319Z","steps":["trace[685147794] 'process raft request' (duration: 119.062933ms)","trace[685147794] 'compare' (duration: 15.904346ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:13:18.200873Z","caller":"traceutil/trace.go:171","msg":"trace[2001868722] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"114.459452ms","start":"2026-08-28T04:13:18.086375Z","end":"2026-08-28T04:13:18.200835Z","steps":["trace[2001868722] 'process raft request' (duration: 105.874761ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:18.214871Z","caller":"traceutil/trace.go:171","msg":"trace[925796999] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"109.595387ms","start":"2026-08-28T04:13:18.10521Z","end":"2026-08-28T04:13:18.214806Z","steps":["trace[925796999] 'process raft request' (duration: 109.164027ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:18.215179Z","caller":"traceutil/trace.go:171","msg":"trace[769414874] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"104.127209ms","start":"2026-08-28T04:13:18.111032Z","end":"2026-08-28T04:13:18.215159Z","steps":["trace[769414874] 'process raft request' (duration: 103.635328ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:24.416062Z","caller":"traceutil/trace.go:171","msg":"trace[1917330287] transaction","detail":"{read_only:false; response_revision:4947; number_of_response:1; }","duration":"118.282371ms","start":"2026-08-28T04:13:24.29774Z","end":"2026-08-28T04:13:24.416023Z","steps":["trace[1917330287] 'process raft request' (duration: 62.123819ms)","trace[1917330287] 'compare' (duration: 55.794283ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:13:29.588311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.187705ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:13:29.588739Z","caller":"traceutil/trace.go:171","msg":"trace[68847939] transaction","detail":"{read_only:false; response_revision:5043; number_of_response:1; }","duration":"200.556834ms","start":"2026-08-28T04:13:29.38815Z","end":"2026-08-28T04:13:29.588707Z","steps":["trace[68847939] 'process raft request' (duration: 200.337949ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:29.588768Z","caller":"traceutil/trace.go:171","msg":"trace[1184639489] transaction","detail":"{read_only:false; response_revision:5042; number_of_response:1; }","duration":"349.511284ms","start":"2026-08-28T04:13:29.239227Z","end":"2026-08-28T04:13:29.588739Z","steps":["trace[1184639489] 'process raft request' (duration: 349.196377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:13:29.588918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:29.239208Z","time spent":"349.647288ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:13:29.589094Z","caller":"traceutil/trace.go:171","msg":"trace[877327155] linearizableReadLoop","detail":"{readStateIndex:5238; appliedIndex:5235; }","duration":"151.661192ms","start":"2026-08-28T04:13:29.437376Z","end":"2026-08-28T04:13:29.589037Z","steps":["trace[877327155] 'read index received' (duration: 35.146607ms)","trace[877327155] 'applied index is now lower than readState.Index' (duration: 116.509795ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:13:29.58875Z","caller":"traceutil/trace.go:171","msg":"trace[161429828] transaction","detail":"{read_only:false; response_revision:5041; number_of_response:1; }","duration":"420.191367ms","start":"2026-08-28T04:13:29.168423Z","end":"2026-08-28T04:13:29.588615Z","steps":["trace[161429828] 'process raft request' (duration: 172.577892ms)","trace[161429828] 'compare' (duration: 247.008271ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:13:29.589537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:29.168402Z","time spent":"420.858133ms","remote":"127.0.0.1:52288","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3751,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:13:29.616971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.583643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-69549086-a9d6-4a7d-9e4d-9283276f2f1b\" ","response":"range_response_count:1 size:3766"} {"level":"info","ts":"2026-08-28T04:13:29.617055Z","caller":"traceutil/trace.go:171","msg":"trace[1862723070] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-69549086-a9d6-4a7d-9e4d-9283276f2f1b; range_end:; response_count:1; response_revision:5043; }","duration":"179.723196ms","start":"2026-08-28T04:13:29.437301Z","end":"2026-08-28T04:13:29.617024Z","steps":["trace[1862723070] 'agreement among raft nodes before linearized reading' (duration: 151.912568ms)","trace[1862723070] 'range keys from in-memory index tree' (duration: 27.702706ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:13:40.497513Z","caller":"traceutil/trace.go:171","msg":"trace[177645079] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"139.099464ms","start":"2026-08-28T04:13:40.358386Z","end":"2026-08-28T04:13:40.497485Z","steps":["trace[177645079] 'process raft request' (duration: 134.105432ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:40.498074Z","caller":"traceutil/trace.go:171","msg":"trace[691025769] linearizableReadLoop","detail":"{readStateIndex:5408; appliedIndex:5403; }","duration":"105.328113ms","start":"2026-08-28T04:13:40.392716Z","end":"2026-08-28T04:13:40.498044Z","steps":["trace[691025769] 'read index received' (duration: 99.534342ms)","trace[691025769] 'applied index is now lower than readState.Index' (duration: 5.793391ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:13:40.49827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.544648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-28T04:13:40.498274Z","caller":"traceutil/trace.go:171","msg":"trace[859751407] transaction","detail":"{read_only:false; response_revision:5206; number_of_response:1; }","duration":"131.506053ms","start":"2026-08-28T04:13:40.366734Z","end":"2026-08-28T04:13:40.49824Z","steps":["trace[859751407] 'process raft request' (duration: 131.227357ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:40.498326Z","caller":"traceutil/trace.go:171","msg":"trace[4763134] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5206; }","duration":"105.581119ms","start":"2026-08-28T04:13:40.392706Z","end":"2026-08-28T04:13:40.498287Z","steps":["trace[4763134] 'agreement among raft nodes before linearized reading' (duration: 105.416185ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:40.498372Z","caller":"traceutil/trace.go:171","msg":"trace[201528562] transaction","detail":"{read_only:false; response_revision:5203; number_of_response:1; }","duration":"133.844216ms","start":"2026-08-28T04:13:40.364512Z","end":"2026-08-28T04:13:40.498356Z","steps":["trace[201528562] 'process raft request' (duration: 133.264053ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:40.498334Z","caller":"traceutil/trace.go:171","msg":"trace[930652075] transaction","detail":"{read_only:false; response_revision:5205; number_of_response:1; }","duration":"133.534009ms","start":"2026-08-28T04:13:40.364777Z","end":"2026-08-28T04:13:40.498311Z","steps":["trace[930652075] 'process raft request' (duration: 133.1488ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:40.498602Z","caller":"traceutil/trace.go:171","msg":"trace[1076008053] transaction","detail":"{read_only:false; response_revision:5204; number_of_response:1; }","duration":"133.999169ms","start":"2026-08-28T04:13:40.364596Z","end":"2026-08-28T04:13:40.498595Z","steps":["trace[1076008053] 'process raft request' (duration: 133.288003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:13:46.033795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.271989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18cfdcda9499d0d2\" ","response":"range_response_count:1 size:746"} {"level":"info","ts":"2026-08-28T04:13:46.033903Z","caller":"traceutil/trace.go:171","msg":"trace[393159541] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18cfdcda9499d0d2; range_end:; response_count:1; response_revision:5249; }","duration":"543.413043ms","start":"2026-08-28T04:13:45.490459Z","end":"2026-08-28T04:13:46.033872Z","steps":["trace[393159541] 'range keys from in-memory index tree' (duration: 543.045214ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:13:46.03395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:45.490445Z","time spent":"543.494604ms","remote":"127.0.0.1:52168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":769,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18cfdcda9499d0d2\" "} {"level":"warn","ts":"2026-08-28T04:13:46.03428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.164927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:1455"} {"level":"info","ts":"2026-08-28T04:13:46.034369Z","caller":"traceutil/trace.go:171","msg":"trace[1913667401] transaction","detail":"{read_only:false; response_revision:5250; number_of_response:1; }","duration":"378.762682ms","start":"2026-08-28T04:13:45.655591Z","end":"2026-08-28T04:13:46.034353Z","steps":["trace[1913667401] 'process raft request' (duration: 348.567569ms)","trace[1913667401] 'compare' (duration: 29.235132ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:13:46.034391Z","caller":"traceutil/trace.go:171","msg":"trace[364792745] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5249; }","duration":"543.322891ms","start":"2026-08-28T04:13:45.491041Z","end":"2026-08-28T04:13:46.034364Z","steps":["trace[364792745] 'range keys from in-memory index tree' (duration: 542.774088ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:13:46.034444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:45.491029Z","time spent":"543.404832ms","remote":"127.0.0.1:52302","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":1478,"request content":"key:\"/registry/services/specs/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-08-28T04:13:46.034482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:45.655554Z","time spent":"378.847064ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:13:46.056425Z","caller":"traceutil/trace.go:171","msg":"trace[180734316] transaction","detail":"{read_only:false; response_revision:5251; number_of_response:1; }","duration":"399.975146ms","start":"2026-08-28T04:13:45.656418Z","end":"2026-08-28T04:13:46.056393Z","steps":["trace[180734316] 'process raft request' (duration: 399.807302ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:13:46.056629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:13:45.656368Z","time spent":"400.14879ms","remote":"127.0.0.1:52368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:13:46.524957Z","caller":"traceutil/trace.go:171","msg":"trace[435380422] linearizableReadLoop","detail":"{readStateIndex:5461; appliedIndex:5459; }","duration":"119.041906ms","start":"2026-08-28T04:13:46.405858Z","end":"2026-08-28T04:13:46.524899Z","steps":["trace[435380422] 'read index received' (duration: 3.035198ms)","trace[435380422] 'applied index is now lower than readState.Index' (duration: 116.004997ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:13:46.525224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.330932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-28T04:13:46.52527Z","caller":"traceutil/trace.go:171","msg":"trace[1535108094] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5258; }","duration":"119.423114ms","start":"2026-08-28T04:13:46.405832Z","end":"2026-08-28T04:13:46.525255Z","steps":["trace[1535108094] 'agreement among raft nodes before linearized reading' (duration: 119.22433ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:46.525225Z","caller":"traceutil/trace.go:171","msg":"trace[112870354] transaction","detail":"{read_only:false; response_revision:5258; number_of_response:1; }","duration":"143.393539ms","start":"2026-08-28T04:13:46.38178Z","end":"2026-08-28T04:13:46.525174Z","steps":["trace[112870354] 'process raft request' (duration: 142.932318ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:46.65238Z","caller":"traceutil/trace.go:171","msg":"trace[1073263396] linearizableReadLoop","detail":"{readStateIndex:5462; appliedIndex:5461; }","duration":"113.785159ms","start":"2026-08-28T04:13:46.538559Z","end":"2026-08-28T04:13:46.652344Z","steps":["trace[1073263396] 'read index received' (duration: 80.243061ms)","trace[1073263396] 'applied index is now lower than readState.Index' (duration: 33.540638ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:13:46.65246Z","caller":"traceutil/trace.go:171","msg":"trace[1188030733] transaction","detail":"{read_only:false; response_revision:5259; number_of_response:1; }","duration":"115.536958ms","start":"2026-08-28T04:13:46.536887Z","end":"2026-08-28T04:13:46.652424Z","steps":["trace[1188030733] 'process raft request' (duration: 81.981079ms)","trace[1188030733] 'compare' (duration: 33.321003ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:13:46.652748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.151827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-81082614-72b3-4856-b152-06d30cc28425\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-08-28T04:13:46.6528Z","caller":"traceutil/trace.go:171","msg":"trace[1703703396] range","detail":"{range_begin:/registry/persistentvolumes/pvc-81082614-72b3-4856-b152-06d30cc28425; range_end:; response_count:1; response_revision:5259; }","duration":"114.256399ms","start":"2026-08-28T04:13:46.538529Z","end":"2026-08-28T04:13:46.652785Z","steps":["trace[1703703396] 'agreement among raft nodes before linearized reading' (duration: 113.983603ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:13:58.318005Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2860} {"level":"info","ts":"2026-08-28T04:13:58.415156Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2860,"took":"94.792866ms","hash":1028490651,"current-db-size-bytes":51175424,"current-db-size":"51 MB","current-db-size-in-use-bytes":27815936,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-28T04:13:58.415235Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1028490651,"revision":2860,"compact-revision":-1} {"level":"info","ts":"2026-08-28T04:18:58.325195Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5332} {"level":"info","ts":"2026-08-28T04:18:58.410815Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5332,"took":"83.132656ms","hash":565616840,"current-db-size-bytes":51175424,"current-db-size":"51 MB","current-db-size-in-use-bytes":18964480,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T04:18:58.410892Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":565616840,"revision":5332,"compact-revision":2860} {"level":"info","ts":"2026-08-28T04:23:58.335451Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7400} {"level":"info","ts":"2026-08-28T04:23:58.397351Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7400,"took":"60.243501ms","hash":3861076782,"current-db-size-bytes":51175424,"current-db-size":"51 MB","current-db-size-in-use-bytes":15458304,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-08-28T04:23:58.397428Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3861076782,"revision":7400,"compact-revision":5332}