{"level":"warn","ts":"2026-07-21T13:20:33.92999Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:20:33.930145Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.151:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.151:2380","--initial-cluster=instance=https://199.19.213.151:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.151:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.151:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-21T13:20:33.930245Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-21T13:20:33.930271Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-07-21T13:20:33.930295Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-21T13:20:33.931176Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"]} {"level":"info","ts":"2026-07-21T13:20:33.931271Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.151:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-21T13:20:33.935924Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.345445ms"} {"level":"info","ts":"2026-07-21T13:20:33.944022Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"9abf795dae4ebc2c","cluster-id":"8a186cf11cef5bce"} {"level":"info","ts":"2026-07-21T13:20:33.944092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T13:20:33.944117Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 0"} {"level":"info","ts":"2026-07-21T13:20:33.944126Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 9abf795dae4ebc2c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T13:20:33.944134Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became follower at term 1"} {"level":"info","ts":"2026-07-21T13:20:33.944171Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"warn","ts":"2026-07-21T13:20:33.950926Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T13:20:33.952218Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T13:20:33.953464Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-21T13:20:33.955725Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"9abf795dae4ebc2c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T13:20:33.955994Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"9abf795dae4ebc2c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T13:20:33.956397Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T13:20:33.956976Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T13:20:33.956988Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-21T13:20:33.957252Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c switched to configuration voters=(11150764645655952428)"} {"level":"info","ts":"2026-07-21T13:20:33.957479Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","added-peer-id":"9abf795dae4ebc2c","added-peer-peer-urls":["https://199.19.213.151:2380"]} {"level":"info","ts":"2026-07-21T13:20:33.959736Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-21T13:20:33.959804Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-07-21T13:20:33.959895Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.151:2380"} {"level":"info","ts":"2026-07-21T13:20:33.960155Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"9abf795dae4ebc2c","initial-advertise-peer-urls":["https://199.19.213.151:2380"],"listen-peer-urls":["https://199.19.213.151:2380"],"advertise-client-urls":["https://199.19.213.151:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.151:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T13:20:33.960199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T13:20:34.645123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T13:20:34.645284Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T13:20:34.645379Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgPreVoteResp from 9abf795dae4ebc2c at term 1"} {"level":"info","ts":"2026-07-21T13:20:34.645444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became candidate at term 2"} {"level":"info","ts":"2026-07-21T13:20:34.645481Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c received MsgVoteResp from 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-07-21T13:20:34.645537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"9abf795dae4ebc2c became leader at term 2"} {"level":"info","ts":"2026-07-21T13:20:34.645576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 9abf795dae4ebc2c elected leader 9abf795dae4ebc2c at term 2"} {"level":"info","ts":"2026-07-21T13:20:34.646734Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"9abf795dae4ebc2c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.151:2379]}","request-path":"/0/members/9abf795dae4ebc2c/attributes","cluster-id":"8a186cf11cef5bce","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T13:20:34.646761Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:20:34.646836Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T13:20:34.647121Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T13:20:34.647159Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T13:20:34.646856Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:20:34.648071Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8a186cf11cef5bce","local-member-id":"9abf795dae4ebc2c","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:20:34.648173Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:20:34.648231Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T13:20:34.650775Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T13:20:34.651099Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.151:2379"} {"level":"info","ts":"2026-07-21T13:20:49.276651Z","caller":"traceutil/trace.go:171","msg":"trace[158002201] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"136.996445ms","start":"2026-07-21T13:20:49.139623Z","end":"2026-07-21T13:20:49.27662Z","steps":["trace[158002201] 'process raft request' (duration: 136.83056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:20:59.791202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.839239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:20:59.791639Z","caller":"traceutil/trace.go:171","msg":"trace[529040037] linearizableReadLoop","detail":"{readStateIndex:458; appliedIndex:454; }","duration":"674.72684ms","start":"2026-07-21T13:20:59.11689Z","end":"2026-07-21T13:20:59.791617Z","steps":["trace[529040037] 'read index received' (duration: 269.908532ms)","trace[529040037] 'applied index is now lower than readState.Index' (duration: 404.817068ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:20:59.791609Z","caller":"traceutil/trace.go:171","msg":"trace[75245052] transaction","detail":"{read_only:false; response_revision:440; number_of_response:1; }","duration":"708.148362ms","start":"2026-07-21T13:20:59.083368Z","end":"2026-07-21T13:20:59.791517Z","steps":["trace[75245052] 'process raft request' (duration: 303.371155ms)","trace[75245052] 'compare' (duration: 403.579002ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:20:59.791694Z","caller":"traceutil/trace.go:171","msg":"trace[764974201] transaction","detail":"{read_only:false; response_revision:442; number_of_response:1; }","duration":"707.349889ms","start":"2026-07-21T13:20:59.08428Z","end":"2026-07-21T13:20:59.79163Z","steps":["trace[764974201] 'process raft request' (duration: 707.209685ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:20:59.791781Z","caller":"traceutil/trace.go:171","msg":"trace[978908831] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"705.353911ms","start":"2026-07-21T13:20:59.086408Z","end":"2026-07-21T13:20:59.791762Z","steps":["trace[978908831] 'process raft request' (duration: 705.118894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:20:59.791907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.084265Z","time spent":"707.542675ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:20:59.79187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.083337Z","time spent":"708.389749ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:20:59.792149Z","caller":"traceutil/trace.go:171","msg":"trace[1645487444] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"674.762862ms","start":"2026-07-21T13:20:59.117354Z","end":"2026-07-21T13:20:59.792117Z","steps":["trace[1645487444] 'process raft request' (duration: 674.226956ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:20:59.792177Z","caller":"traceutil/trace.go:171","msg":"trace[1887192396] transaction","detail":"{read_only:false; response_revision:441; number_of_response:1; }","duration":"708.350609ms","start":"2026-07-21T13:20:59.083789Z","end":"2026-07-21T13:20:59.79214Z","steps":["trace[1887192396] 'process raft request' (duration: 707.596047ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:20:59.792271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.117344Z","time spent":"674.870064ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:20:59.792374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.086398Z","time spent":"705.423994ms","remote":"127.0.0.1:46584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4037,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:20:59.792453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.718392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"warn","ts":"2026-07-21T13:20:59.792456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.549504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-x26gk\" ","response":"range_response_count:1 size:4016"} {"level":"info","ts":"2026-07-21T13:20:59.792503Z","caller":"traceutil/trace.go:171","msg":"trace[1756803457] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:444; }","duration":"672.797214ms","start":"2026-07-21T13:20:59.119692Z","end":"2026-07-21T13:20:59.792489Z","steps":["trace[1756803457] 'agreement among raft nodes before linearized reading' (duration: 672.63329ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:20:59.792518Z","caller":"traceutil/trace.go:171","msg":"trace[1338170410] range","detail":"{range_begin:/registry/pods/kube-system/coredns-7c96b6546b-x26gk; range_end:; response_count:1; response_revision:444; }","duration":"675.642176ms","start":"2026-07-21T13:20:59.116858Z","end":"2026-07-21T13:20:59.7925Z","steps":["trace[1338170410] 'agreement among raft nodes before linearized reading' (duration: 675.513883ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:20:59.792541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.119685Z","time spent":"672.847935ms","remote":"127.0.0.1:46882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4219,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-07-21T13:20:59.792562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.116843Z","time spent":"675.706749ms","remote":"127.0.0.1:46584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4040,"request content":"key:\"/registry/pods/kube-system/coredns-7c96b6546b-x26gk\" "} {"level":"warn","ts":"2026-07-21T13:20:59.792717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:20:59.08377Z","time spent":"708.464222ms","remote":"127.0.0.1:46914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3795,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:20:59.999718Z","caller":"traceutil/trace.go:171","msg":"trace[211366869] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"141.687091ms","start":"2026-07-21T13:20:59.857995Z","end":"2026-07-21T13:20:59.999682Z","steps":["trace[211366869] 'process raft request' (duration: 47.251861ms)","trace[211366869] 'compare' (duration: 94.178373ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:21:00.000037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.999825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:21:00.0001Z","caller":"traceutil/trace.go:171","msg":"trace[146704368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:464; }","duration":"103.11621ms","start":"2026-07-21T13:20:59.896972Z","end":"2026-07-21T13:21:00.000088Z","steps":["trace[146704368] 'agreement among raft nodes before linearized reading' (duration: 103.012447ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:20:59.999956Z","caller":"traceutil/trace.go:171","msg":"trace[216398316] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"102.558713ms","start":"2026-07-21T13:20:59.89702Z","end":"2026-07-21T13:20:59.999578Z","steps":["trace[216398316] 'read index received' (duration: 8.201266ms)","trace[216398316] 'applied index is now lower than readState.Index' (duration: 94.355577ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:21:13.710709Z","caller":"traceutil/trace.go:171","msg":"trace[542092790] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"398.901902ms","start":"2026-07-21T13:21:13.311783Z","end":"2026-07-21T13:21:13.710685Z","steps":["trace[542092790] 'process raft request' (duration: 398.669576ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:13.710734Z","caller":"traceutil/trace.go:171","msg":"trace[518402053] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"398.859211ms","start":"2026-07-21T13:21:13.311842Z","end":"2026-07-21T13:21:13.710701Z","steps":["trace[518402053] 'process raft request' (duration: 398.726917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.710828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.311758Z","time spent":"399.007105ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:21:13.710962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.311836Z","time spent":"398.976134ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.711093Z","caller":"traceutil/trace.go:171","msg":"trace[1864813421] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"393.466963ms","start":"2026-07-21T13:21:13.317618Z","end":"2026-07-21T13:21:13.711085Z","steps":["trace[1864813421] 'process raft request' (duration: 392.993439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.711154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.317595Z","time spent":"393.518753ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.711251Z","caller":"traceutil/trace.go:171","msg":"trace[1650730248] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"387.842736ms","start":"2026-07-21T13:21:13.323401Z","end":"2026-07-21T13:21:13.711244Z","steps":["trace[1650730248] 'process raft request' (duration: 387.247649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.71128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.323382Z","time spent":"387.885788ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.71147Z","caller":"traceutil/trace.go:171","msg":"trace[1636015711] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"387.9963ms","start":"2026-07-21T13:21:13.323468Z","end":"2026-07-21T13:21:13.711464Z","steps":["trace[1636015711] 'process raft request' (duration: 387.719743ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.711551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.323463Z","time spent":"388.025382ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.711643Z","caller":"traceutil/trace.go:171","msg":"trace[89371794] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"384.172948ms","start":"2026-07-21T13:21:13.327464Z","end":"2026-07-21T13:21:13.711637Z","steps":["trace[89371794] 'process raft request' (duration: 383.9112ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:13.711645Z","caller":"traceutil/trace.go:171","msg":"trace[1912743862] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"358.267655ms","start":"2026-07-21T13:21:13.353356Z","end":"2026-07-21T13:21:13.711624Z","steps":["trace[1912743862] 'process raft request' (duration: 358.193373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.711713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.327456Z","time spent":"384.203199ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:21:13.711759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.353334Z","time spent":"358.385308ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.71183Z","caller":"traceutil/trace.go:171","msg":"trace[312541888] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"383.647623ms","start":"2026-07-21T13:21:13.328164Z","end":"2026-07-21T13:21:13.711812Z","steps":["trace[312541888] 'process raft request' (duration: 383.269752ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:13.711882Z","caller":"traceutil/trace.go:171","msg":"trace[2092868378] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"374.349578ms","start":"2026-07-21T13:21:13.33752Z","end":"2026-07-21T13:21:13.711869Z","steps":["trace[2092868378] 'process raft request' (duration: 373.964598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.711944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.337504Z","time spent":"374.410701ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:21:13.712018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.328157Z","time spent":"383.752976ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.711895Z","caller":"traceutil/trace.go:171","msg":"trace[41503608] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"388.088523ms","start":"2026-07-21T13:21:13.323799Z","end":"2026-07-21T13:21:13.711888Z","steps":["trace[41503608] 'process raft request' (duration: 387.451804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.712191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.323782Z","time spent":"388.377512ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.717939Z","caller":"traceutil/trace.go:171","msg":"trace[1378961448] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"335.34697ms","start":"2026-07-21T13:21:13.382558Z","end":"2026-07-21T13:21:13.717905Z","steps":["trace[1378961448] 'process raft request' (duration: 335.021671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.718074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.382533Z","time spent":"335.476293ms","remote":"127.0.0.1:46950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:21:13.718055Z","caller":"traceutil/trace.go:171","msg":"trace[807741788] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"229.056639ms","start":"2026-07-21T13:21:13.488971Z","end":"2026-07-21T13:21:13.718028Z","steps":["trace[807741788] 'process raft request' (duration: 228.977557ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:13.718103Z","caller":"traceutil/trace.go:171","msg":"trace[1552755462] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"333.827135ms","start":"2026-07-21T13:21:13.384261Z","end":"2026-07-21T13:21:13.718088Z","steps":["trace[1552755462] 'process raft request' (duration: 333.501055ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.718204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.384247Z","time spent":"333.910298ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:21:13.718283Z","caller":"traceutil/trace.go:171","msg":"trace[1215566978] transaction","detail":"{read_only:false; number_of_response:1; response_revision:542; }","duration":"333.640129ms","start":"2026-07-21T13:21:13.384615Z","end":"2026-07-21T13:21:13.718256Z","steps":["trace[1215566978] 'process raft request' (duration: 333.221738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:13.718405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:13.384607Z","time spent":"333.727812ms","remote":"127.0.0.1:46452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:21:20.028255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.630219ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:21:20.028373Z","caller":"traceutil/trace.go:171","msg":"trace[737146306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"128.797935ms","start":"2026-07-21T13:21:19.89955Z","end":"2026-07-21T13:21:20.028348Z","steps":["trace[737146306] 'range keys from in-memory index tree' (duration: 128.531377ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:20.181817Z","caller":"traceutil/trace.go:171","msg":"trace[1994251529] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"148.939858ms","start":"2026-07-21T13:21:20.032766Z","end":"2026-07-21T13:21:20.181706Z","steps":["trace[1994251529] 'process raft request' (duration: 145.329971ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:20.554039Z","caller":"traceutil/trace.go:171","msg":"trace[1587337647] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"366.454806ms","start":"2026-07-21T13:21:20.187555Z","end":"2026-07-21T13:21:20.55401Z","steps":["trace[1587337647] 'process raft request' (duration: 292.472057ms)","trace[1587337647] 'compare' (duration: 73.399351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:21:20.554232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.187532Z","time spent":"366.619581ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:21:20.554641Z","caller":"traceutil/trace.go:171","msg":"trace[1669494505] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"333.144794ms","start":"2026-07-21T13:21:20.22147Z","end":"2026-07-21T13:21:20.554615Z","steps":["trace[1669494505] 'process raft request' (duration: 332.434654ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:20.554762Z","caller":"traceutil/trace.go:171","msg":"trace[516358425] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"296.369981ms","start":"2026-07-21T13:21:20.258248Z","end":"2026-07-21T13:21:20.554618Z","steps":["trace[516358425] 'process raft request' (duration: 296.259468ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:20.554804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.221447Z","time spent":"333.279199ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:21:21.07313Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387970977701195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:21:21.62695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.238407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:21:21.627041Z","caller":"traceutil/trace.go:171","msg":"trace[767801211] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"1.05427967s","start":"2026-07-21T13:21:20.572746Z","end":"2026-07-21T13:21:21.627026Z","steps":["trace[767801211] 'read index received' (duration: 922.003313ms)","trace[767801211] 'applied index is now lower than readState.Index' (duration: 132.274977ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:21:21.627187Z","caller":"traceutil/trace.go:171","msg":"trace[822946625] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"1.054465727s","start":"2026-07-21T13:21:20.572709Z","end":"2026-07-21T13:21:21.627175Z","steps":["trace[822946625] 'process raft request' (duration: 921.939642ms)","trace[822946625] 'compare' (duration: 131.920937ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:21:21.627244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.572688Z","time spent":"1.054534208s","remote":"127.0.0.1:46584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T13:21:21.627476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.039203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-htrt2\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-07-21T13:21:21.627507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.991456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:21:21.62762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054871668s","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-07-21T13:21:21.627612Z","caller":"traceutil/trace.go:171","msg":"trace[1611680248] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:643; }","duration":"730.140271ms","start":"2026-07-21T13:21:20.897439Z","end":"2026-07-21T13:21:21.627579Z","steps":["trace[1611680248] 'agreement among raft nodes before linearized reading' (duration: 729.933414ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:21.627657Z","caller":"traceutil/trace.go:171","msg":"trace[1498618506] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:643; }","duration":"1.054941791s","start":"2026-07-21T13:21:20.572705Z","end":"2026-07-21T13:21:21.627647Z","steps":["trace[1498618506] 'agreement among raft nodes before linearized reading' (duration: 1.054813807s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:21.62767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.897419Z","time spent":"730.242034ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T13:21:21.627693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.572685Z","time spent":"1.054998302s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:21:21.627526Z","caller":"traceutil/trace.go:171","msg":"trace[2002119409] range","detail":"{range_begin:/registry/pods/kube-system/cilium-htrt2; range_end:; response_count:1; response_revision:643; }","duration":"982.136685ms","start":"2026-07-21T13:21:20.645374Z","end":"2026-07-21T13:21:21.627511Z","steps":["trace[2002119409] 'agreement among raft nodes before linearized reading' (duration: 981.874847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:21.627787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:20.645352Z","time spent":"982.375963ms","remote":"127.0.0.1:46584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-htrt2\" "} {"level":"warn","ts":"2026-07-21T13:21:21.62873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.59152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T13:21:21.628795Z","caller":"traceutil/trace.go:171","msg":"trace[653914404] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:643; }","duration":"532.708314ms","start":"2026-07-21T13:21:21.096071Z","end":"2026-07-21T13:21:21.62878Z","steps":["trace[653914404] 'agreement among raft nodes before linearized reading' (duration: 531.272892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:21.628832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:21.096045Z","time spent":"532.778737ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-21T13:21:23.036983Z","caller":"traceutil/trace.go:171","msg":"trace[2027129826] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"209.478091ms","start":"2026-07-21T13:21:22.827459Z","end":"2026-07-21T13:21:23.036937Z","steps":["trace[2027129826] 'process raft request' (duration: 204.915667ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:23.037058Z","caller":"traceutil/trace.go:171","msg":"trace[1973597654] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:685; }","duration":"137.066328ms","start":"2026-07-21T13:21:22.899976Z","end":"2026-07-21T13:21:23.037042Z","steps":["trace[1973597654] 'read index received' (duration: 132.39525ms)","trace[1973597654] 'applied index is now lower than readState.Index' (duration: 4.668888ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:21:23.041364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.375635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:21:23.041454Z","caller":"traceutil/trace.go:171","msg":"trace[981985636] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:660; }","duration":"141.54388ms","start":"2026-07-21T13:21:22.899887Z","end":"2026-07-21T13:21:23.04143Z","steps":["trace[981985636] 'agreement among raft nodes before linearized reading' (duration: 137.189991ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:38.133541Z","caller":"traceutil/trace.go:171","msg":"trace[2035589801] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"312.790216ms","start":"2026-07-21T13:21:37.820711Z","end":"2026-07-21T13:21:38.133501Z","steps":["trace[2035589801] 'process raft request' (duration: 312.501297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:38.13395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:37.820687Z","time spent":"312.9958ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:21:38.140016Z","caller":"traceutil/trace.go:171","msg":"trace[431390903] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"316.640148ms","start":"2026-07-21T13:21:37.823359Z","end":"2026-07-21T13:21:38.139999Z","steps":["trace[431390903] 'process raft request' (duration: 316.460663ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:21:38.140204Z","caller":"traceutil/trace.go:171","msg":"trace[953508144] linearizableReadLoop","detail":"{readStateIndex:837; appliedIndex:835; }","duration":"314.147906ms","start":"2026-07-21T13:21:37.826025Z","end":"2026-07-21T13:21:38.140173Z","steps":["trace[953508144] 'read index received' (duration: 307.2117ms)","trace[953508144] 'applied index is now lower than readState.Index' (duration: 6.934556ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:21:38.140292Z","caller":"traceutil/trace.go:171","msg":"trace[1955245623] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"312.697254ms","start":"2026-07-21T13:21:37.827571Z","end":"2026-07-21T13:21:38.140268Z","steps":["trace[1955245623] 'process raft request' (duration: 312.390744ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:38.140236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:37.823336Z","time spent":"316.760932ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:21:38.140382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:37.827556Z","time spent":"312.781145ms","remote":"127.0.0.1:46584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:21:38.140509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.768294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:21:38.140539Z","caller":"traceutil/trace.go:171","msg":"trace[1477771606] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"239.836756ms","start":"2026-07-21T13:21:37.900692Z","end":"2026-07-21T13:21:38.140529Z","steps":["trace[1477771606] 'agreement among raft nodes before linearized reading' (duration: 239.791445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:38.140553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.528296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:21:38.140574Z","caller":"traceutil/trace.go:171","msg":"trace[1215819080] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"314.578308ms","start":"2026-07-21T13:21:37.82599Z","end":"2026-07-21T13:21:38.140569Z","steps":["trace[1215819080] 'agreement among raft nodes before linearized reading' (duration: 314.28989ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:21:38.140588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:21:37.825977Z","time spent":"314.607819ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:21:38.141053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.018877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:21:38.141107Z","caller":"traceutil/trace.go:171","msg":"trace[2113437447] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:806; }","duration":"287.122999ms","start":"2026-07-21T13:21:37.853971Z","end":"2026-07-21T13:21:38.141094Z","steps":["trace[2113437447] 'agreement among raft nodes before linearized reading' (duration: 286.439879ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:22:14.213264Z","caller":"traceutil/trace.go:171","msg":"trace[1073516865] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"263.179838ms","start":"2026-07-21T13:22:13.950057Z","end":"2026-07-21T13:22:14.213237Z","steps":["trace[1073516865] 'process raft request' (duration: 263.012623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:14.46351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.470019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:22:14.463598Z","caller":"traceutil/trace.go:171","msg":"trace[1512989215] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1025; }","duration":"163.585373ms","start":"2026-07-21T13:22:14.299986Z","end":"2026-07-21T13:22:14.463572Z","steps":["trace[1512989215] 'range keys from in-memory index tree' (duration: 163.364526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:14.463889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.412052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:22:14.463937Z","caller":"traceutil/trace.go:171","msg":"trace[1374198157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1025; }","duration":"122.495864ms","start":"2026-07-21T13:22:14.341428Z","end":"2026-07-21T13:22:14.463924Z","steps":["trace[1374198157] 'range keys from in-memory index tree' (duration: 122.263797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:14.464153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.910552ms","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-07-21T13:22:14.464245Z","caller":"traceutil/trace.go:171","msg":"trace[2142107666] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1025; }","duration":"116.062977ms","start":"2026-07-21T13:22:14.348161Z","end":"2026-07-21T13:22:14.464224Z","steps":["trace[2142107666] 'range keys from in-memory index tree' (duration: 115.707485ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:22:40.634323Z","caller":"traceutil/trace.go:171","msg":"trace[1372104326] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"307.875963ms","start":"2026-07-21T13:22:40.326424Z","end":"2026-07-21T13:22:40.6343Z","steps":["trace[1372104326] 'process raft request' (duration: 307.454421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:40.634445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.326399Z","time spent":"307.981615ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:22:41.388537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.674785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:22:41.388925Z","caller":"traceutil/trace.go:171","msg":"trace[873733679] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"750.565348ms","start":"2026-07-21T13:22:40.638342Z","end":"2026-07-21T13:22:41.388908Z","steps":["trace[873733679] 'process raft request' (duration: 501.44535ms)","trace[873733679] 'compare' (duration: 248.590584ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:22:41.389023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.638325Z","time spent":"750.63047ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:22:41.389942Z","caller":"traceutil/trace.go:171","msg":"trace[584408361] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1156; }","duration":"489.574994ms","start":"2026-07-21T13:22:40.900339Z","end":"2026-07-21T13:22:41.389914Z","steps":["trace[584408361] 'read index received' (duration: 239.439286ms)","trace[584408361] 'applied index is now lower than readState.Index' (duration: 250.134828ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:22:41.390059Z","caller":"traceutil/trace.go:171","msg":"trace[1376427844] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"751.504316ms","start":"2026-07-21T13:22:40.63854Z","end":"2026-07-21T13:22:41.390044Z","steps":["trace[1376427844] 'process raft request' (duration: 751.215967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:41.390202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.638509Z","time spent":"751.580747ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:22:41.39039Z","caller":"traceutil/trace.go:171","msg":"trace[1300228360] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"751.632701ms","start":"2026-07-21T13:22:40.638697Z","end":"2026-07-21T13:22:41.39033Z","steps":["trace[1300228360] 'process raft request' (duration: 751.177857ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:41.390471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.638689Z","time spent":"751.747214ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:22:41.390536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.205193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:22:41.39057Z","caller":"traceutil/trace.go:171","msg":"trace[1926479021] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1114; }","duration":"490.311416ms","start":"2026-07-21T13:22:40.900249Z","end":"2026-07-21T13:22:41.39056Z","steps":["trace[1926479021] 'agreement among raft nodes before linearized reading' (duration: 490.261354ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:41.390595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.900227Z","time spent":"490.361277ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T13:22:41.390864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.571118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T13:22:41.390896Z","caller":"traceutil/trace.go:171","msg":"trace[640392998] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1114; }","duration":"423.630511ms","start":"2026-07-21T13:22:40.967257Z","end":"2026-07-21T13:22:41.390888Z","steps":["trace[640392998] 'agreement among raft nodes before linearized reading' (duration: 423.490427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:41.390915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:40.967243Z","time spent":"423.667501ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T13:22:42.615636Z","caller":"traceutil/trace.go:171","msg":"trace[16070684] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"440.202294ms","start":"2026-07-21T13:22:42.175404Z","end":"2026-07-21T13:22:42.615607Z","steps":["trace[16070684] 'process raft request' (duration: 440.04275ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:22:42.615834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:22:42.175368Z","time spent":"440.380659ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:22:53.790292Z","caller":"traceutil/trace.go:171","msg":"trace[933823943] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"104.383135ms","start":"2026-07-21T13:22:53.685886Z","end":"2026-07-21T13:22:53.790269Z","steps":["trace[933823943] 'process raft request' (duration: 104.244031ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:22:57.874606Z","caller":"traceutil/trace.go:171","msg":"trace[1935652039] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"123.657858ms","start":"2026-07-21T13:22:57.750914Z","end":"2026-07-21T13:22:57.874572Z","steps":["trace[1935652039] 'process raft request' (duration: 123.554655ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:22:57.874665Z","caller":"traceutil/trace.go:171","msg":"trace[159536747] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"124.282066ms","start":"2026-07-21T13:22:57.750356Z","end":"2026-07-21T13:22:57.874638Z","steps":["trace[159536747] 'process raft request' (duration: 123.589016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:01.093292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.169285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:01.093356Z","caller":"traceutil/trace.go:171","msg":"trace[77211046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"194.283098ms","start":"2026-07-21T13:23:00.899061Z","end":"2026-07-21T13:23:01.093344Z","steps":["trace[77211046] 'range keys from in-memory index tree' (duration: 194.109324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:01.0936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.040132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:01.093671Z","caller":"traceutil/trace.go:171","msg":"trace[1906542008] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1228; }","duration":"182.152725ms","start":"2026-07-21T13:23:00.911501Z","end":"2026-07-21T13:23:01.093654Z","steps":["trace[1906542008] 'count revisions from in-memory index tree' (duration: 181.97785ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:03.030617Z","caller":"traceutil/trace.go:171","msg":"trace[1428392193] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"132.536677ms","start":"2026-07-21T13:23:02.898046Z","end":"2026-07-21T13:23:03.030583Z","steps":["trace[1428392193] 'read index received' (duration: 132.348552ms)","trace[1428392193] 'applied index is now lower than readState.Index' (duration: 187.105µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:03.03082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.744464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:03.030856Z","caller":"traceutil/trace.go:171","msg":"trace[181816917] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1234; }","duration":"132.840435ms","start":"2026-07-21T13:23:02.898003Z","end":"2026-07-21T13:23:03.030843Z","steps":["trace[181816917] 'agreement among raft nodes before linearized reading' (duration: 132.720212ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:03.031239Z","caller":"traceutil/trace.go:171","msg":"trace[1445423244] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"242.622739ms","start":"2026-07-21T13:23:02.788599Z","end":"2026-07-21T13:23:03.031221Z","steps":["trace[1445423244] 'process raft request' (duration: 241.785554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:03.336866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.604119ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:23:03.337079Z","caller":"traceutil/trace.go:171","msg":"trace[1158066576] linearizableReadLoop","detail":"{readStateIndex:1286; appliedIndex:1285; }","duration":"304.337959ms","start":"2026-07-21T13:23:03.032725Z","end":"2026-07-21T13:23:03.337063Z","steps":["trace[1158066576] 'read index received' (duration: 50.40879ms)","trace[1158066576] 'applied index is now lower than readState.Index' (duration: 253.927609ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:03.3371Z","caller":"traceutil/trace.go:171","msg":"trace[1528308598] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"432.637553ms","start":"2026-07-21T13:23:02.904429Z","end":"2026-07-21T13:23:03.337066Z","steps":["trace[1528308598] 'process raft request' (duration: 178.728265ms)","trace[1528308598] 'compare' (duration: 253.315001ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:03.337252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:02.904399Z","time spent":"432.769497ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:23:03.341917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.176769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:03.342013Z","caller":"traceutil/trace.go:171","msg":"trace[1063307178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"309.284792ms","start":"2026-07-21T13:23:03.032706Z","end":"2026-07-21T13:23:03.341991Z","steps":["trace[1063307178] 'agreement among raft nodes before linearized reading' (duration: 304.39852ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:03.342057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:03.032656Z","time spent":"309.392826ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T13:23:17.906646Z","caller":"traceutil/trace.go:171","msg":"trace[1432586408] linearizableReadLoop","detail":"{readStateIndex:1335; appliedIndex:1334; }","duration":"309.689282ms","start":"2026-07-21T13:23:17.596938Z","end":"2026-07-21T13:23:17.906627Z","steps":["trace[1432586408] 'read index received' (duration: 309.507656ms)","trace[1432586408] 'applied index is now lower than readState.Index' (duration: 180.746µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:17.906946Z","caller":"traceutil/trace.go:171","msg":"trace[1159251301] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"330.771783ms","start":"2026-07-21T13:23:17.576161Z","end":"2026-07-21T13:23:17.906933Z","steps":["trace[1159251301] 'process raft request' (duration: 330.35362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:17.907036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.576141Z","time spent":"330.831184ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:23:17.907282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.341493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:23:17.907313Z","caller":"traceutil/trace.go:171","msg":"trace[1148463435] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1281; }","duration":"310.398204ms","start":"2026-07-21T13:23:17.596905Z","end":"2026-07-21T13:23:17.907303Z","steps":["trace[1148463435] 'agreement among raft nodes before linearized reading' (duration: 310.293341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:17.907339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.59689Z","time spent":"310.443386ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:23:17.90752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.488447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T13:23:17.907546Z","caller":"traceutil/trace.go:171","msg":"trace[2099777798] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1281; }","duration":"310.522478ms","start":"2026-07-21T13:23:17.597015Z","end":"2026-07-21T13:23:17.907538Z","steps":["trace[2099777798] 'agreement among raft nodes before linearized reading' (duration: 310.452336ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:17.90757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.597011Z","time spent":"310.553709ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T13:23:17.907899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.411131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.151\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-21T13:23:17.90793Z","caller":"traceutil/trace.go:171","msg":"trace[1167389860] range","detail":"{range_begin:/registry/masterleases/199.19.213.151; range_end:; response_count:1; response_revision:1281; }","duration":"212.486912ms","start":"2026-07-21T13:23:17.695435Z","end":"2026-07-21T13:23:17.907921Z","steps":["trace[1167389860] 'agreement among raft nodes before linearized reading' (duration: 212.40718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:17.908201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.423041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-21T13:23:17.908235Z","caller":"traceutil/trace.go:171","msg":"trace[249772551] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1281; }","duration":"268.530194ms","start":"2026-07-21T13:23:17.639695Z","end":"2026-07-21T13:23:17.908225Z","steps":["trace[249772551] 'agreement among raft nodes before linearized reading' (duration: 268.432451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:18.778152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.982488ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:41"} {"level":"warn","ts":"2026-07-21T13:23:18.778227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.909781Z","time spent":"868.440269ms","remote":"127.0.0.1:46438","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-21T13:23:18.785924Z","caller":"traceutil/trace.go:171","msg":"trace[1705818289] linearizableReadLoop","detail":"{readStateIndex:1339; appliedIndex:1335; }","duration":"691.968521ms","start":"2026-07-21T13:23:18.093939Z","end":"2026-07-21T13:23:18.785908Z","steps":["trace[1705818289] 'read index received' (duration: 314.298049ms)","trace[1705818289] 'applied index is now lower than readState.Index' (duration: 377.669441ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:18.786043Z","caller":"traceutil/trace.go:171","msg":"trace[1059822615] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"872.462838ms","start":"2026-07-21T13:23:17.913566Z","end":"2026-07-21T13:23:18.786029Z","steps":["trace[1059822615] 'process raft request' (duration: 872.313892ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:18.786038Z","caller":"traceutil/trace.go:171","msg":"trace[1418917075] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"872.533628ms","start":"2026-07-21T13:23:17.91347Z","end":"2026-07-21T13:23:18.786004Z","steps":["trace[1418917075] 'process raft request' (duration: 872.367423ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:18.78618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.913544Z","time spent":"872.544849ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:23:18.786079Z","caller":"traceutil/trace.go:171","msg":"trace[1242388793] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"874.094538ms","start":"2026-07-21T13:23:17.911963Z","end":"2026-07-21T13:23:18.786057Z","steps":["trace[1242388793] 'process raft request' (duration: 873.726826ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:18.786266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.91346Z","time spent":"872.711145ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:23:18.786367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:17.911951Z","time spent":"874.350027ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:23:18.786527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.56713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-21T13:23:18.786569Z","caller":"traceutil/trace.go:171","msg":"trace[1866334415] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1284; }","duration":"692.657793ms","start":"2026-07-21T13:23:18.0939Z","end":"2026-07-21T13:23:18.786558Z","steps":["trace[1866334415] 'agreement among raft nodes before linearized reading' (duration: 692.078874ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:18.786604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:18.093884Z","time spent":"692.712544ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T13:23:19.409845Z","caller":"traceutil/trace.go:171","msg":"trace[462218313] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"276.725674ms","start":"2026-07-21T13:23:19.133088Z","end":"2026-07-21T13:23:19.409813Z","steps":["trace[462218313] 'process raft request' (duration: 209.26993ms)","trace[462218313] 'compare' (duration: 66.898767ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:20.046253Z","caller":"traceutil/trace.go:171","msg":"trace[1619123211] linearizableReadLoop","detail":"{readStateIndex:1344; appliedIndex:1343; }","duration":"135.139215ms","start":"2026-07-21T13:23:19.911079Z","end":"2026-07-21T13:23:20.046218Z","steps":["trace[1619123211] 'read index received' (duration: 134.66103ms)","trace[1619123211] 'applied index is now lower than readState.Index' (duration: 476.715µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:20.046273Z","caller":"traceutil/trace.go:171","msg":"trace[710160787] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"142.178499ms","start":"2026-07-21T13:23:19.90407Z","end":"2026-07-21T13:23:20.046248Z","steps":["trace[710160787] 'process raft request' (duration: 141.969312ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:20.046596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.489256ms","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-07-21T13:23:20.046887Z","caller":"traceutil/trace.go:171","msg":"trace[1483484409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1289; }","duration":"135.832247ms","start":"2026-07-21T13:23:19.911034Z","end":"2026-07-21T13:23:20.046866Z","steps":["trace[1483484409] 'agreement among raft nodes before linearized reading' (duration: 135.30636ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:20.347869Z","caller":"traceutil/trace.go:171","msg":"trace[1333416434] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"296.999889ms","start":"2026-07-21T13:23:20.050842Z","end":"2026-07-21T13:23:20.347842Z","steps":["trace[1333416434] 'process raft request' (duration: 295.754529ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:21.145754Z","caller":"traceutil/trace.go:171","msg":"trace[1306113645] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1345; }","duration":"248.200468ms","start":"2026-07-21T13:23:20.897533Z","end":"2026-07-21T13:23:21.145733Z","steps":["trace[1306113645] 'read index received' (duration: 48.021706ms)","trace[1306113645] 'applied index is now lower than readState.Index' (duration: 200.177982ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:21.145892Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.338462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:21.145927Z","caller":"traceutil/trace.go:171","msg":"trace[1465833343] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"248.420714ms","start":"2026-07-21T13:23:20.897494Z","end":"2026-07-21T13:23:21.145915Z","steps":["trace[1465833343] 'agreement among raft nodes before linearized reading' (duration: 248.329561ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:21.14626Z","caller":"traceutil/trace.go:171","msg":"trace[967234072] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"267.80216ms","start":"2026-07-21T13:23:20.878436Z","end":"2026-07-21T13:23:21.146238Z","steps":["trace[967234072] 'process raft request' (duration: 267.230072ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:21.146303Z","caller":"traceutil/trace.go:171","msg":"trace[1022990615] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"267.728318ms","start":"2026-07-21T13:23:20.878526Z","end":"2026-07-21T13:23:21.146254Z","steps":["trace[1022990615] 'process raft request' (duration: 267.176461ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:21.14651Z","caller":"traceutil/trace.go:171","msg":"trace[18152052] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"268.128812ms","start":"2026-07-21T13:23:20.87837Z","end":"2026-07-21T13:23:21.146498Z","steps":["trace[18152052] 'process raft request' (duration: 267.124909ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:21.638937Z","caller":"traceutil/trace.go:171","msg":"trace[1555669731] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"105.003528ms","start":"2026-07-21T13:23:21.533904Z","end":"2026-07-21T13:23:21.638907Z","steps":["trace[1555669731] 'process raft request' (duration: 35.356444ms)","trace[1555669731] 'compare' (duration: 69.53633ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:22.281139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.640251ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:23:22.281236Z","caller":"traceutil/trace.go:171","msg":"trace[114361078] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"380.473112ms","start":"2026-07-21T13:23:21.900749Z","end":"2026-07-21T13:23:22.281222Z","steps":["trace[114361078] 'read index received' (duration: 8.609774ms)","trace[114361078] 'applied index is now lower than readState.Index' (duration: 371.861588ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:22.281324Z","caller":"traceutil/trace.go:171","msg":"trace[1522205113] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"535.818979ms","start":"2026-07-21T13:23:21.745456Z","end":"2026-07-21T13:23:22.281275Z","steps":["trace[1522205113] 'process raft request' (duration: 163.965691ms)","trace[1522205113] 'compare' (duration: 371.248088ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:22.28153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.591445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:23:22.281544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:21.745434Z","time spent":"536.013145ms","remote":"127.0.0.1:46488","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T13:23:22.281585Z","caller":"traceutil/trace.go:171","msg":"trace[670489138] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1297; }","duration":"380.876124ms","start":"2026-07-21T13:23:21.900698Z","end":"2026-07-21T13:23:22.281574Z","steps":["trace[670489138] 'agreement among raft nodes before linearized reading' (duration: 380.558854ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:22.281617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:21.900678Z","time spent":"380.930455ms","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-21T13:23:22.554924Z","caller":"traceutil/trace.go:171","msg":"trace[1889436496] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"198.904831ms","start":"2026-07-21T13:23:22.355999Z","end":"2026-07-21T13:23:22.554904Z","steps":["trace[1889436496] 'process raft request' (duration: 198.405676ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:34.577828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.445837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-21T13:23:34.577923Z","caller":"traceutil/trace.go:171","msg":"trace[1236123988] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1396; }","duration":"333.606061ms","start":"2026-07-21T13:23:34.244296Z","end":"2026-07-21T13:23:34.577902Z","steps":["trace[1236123988] 'range keys from in-memory index tree' (duration: 333.20662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:34.577969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:34.244257Z","time spent":"333.701565ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-21T13:23:34.577854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.383231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:23:34.578093Z","caller":"traceutil/trace.go:171","msg":"trace[108444123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1396; }","duration":"280.66441ms","start":"2026-07-21T13:23:34.297409Z","end":"2026-07-21T13:23:34.578074Z","steps":["trace[108444123] 'range keys from in-memory index tree' (duration: 280.260257ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:23:34.862445Z","caller":"traceutil/trace.go:171","msg":"trace[1224754760] linearizableReadLoop","detail":"{readStateIndex:1455; appliedIndex:1454; }","duration":"246.497634ms","start":"2026-07-21T13:23:34.615924Z","end":"2026-07-21T13:23:34.862422Z","steps":["trace[1224754760] 'read index received' (duration: 246.279887ms)","trace[1224754760] 'applied index is now lower than readState.Index' (duration: 216.857µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:34.862548Z","caller":"traceutil/trace.go:171","msg":"trace[1692098331] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"280.431602ms","start":"2026-07-21T13:23:34.582101Z","end":"2026-07-21T13:23:34.862533Z","steps":["trace[1692098331] 'process raft request' (duration: 280.173584ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:34.862685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.751681ms","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-07-21T13:23:34.862749Z","caller":"traceutil/trace.go:171","msg":"trace[1566330223] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1397; }","duration":"246.872975ms","start":"2026-07-21T13:23:34.61586Z","end":"2026-07-21T13:23:34.862733Z","steps":["trace[1566330223] 'agreement among raft nodes before linearized reading' (duration: 246.667698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:35.529779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.783011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T13:23:35.529968Z","caller":"traceutil/trace.go:171","msg":"trace[1334050065] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"663.358322ms","start":"2026-07-21T13:23:34.866587Z","end":"2026-07-21T13:23:35.529945Z","steps":["trace[1334050065] 'process raft request' (duration: 484.323032ms)","trace[1334050065] 'compare' (duration: 178.654988ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:23:35.530087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.631071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:23:35.53013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:34.866569Z","time spent":"663.444984ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:23:35.529961Z","caller":"traceutil/trace.go:171","msg":"trace[1081624953] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1455; }","duration":"630.480236ms","start":"2026-07-21T13:23:34.899456Z","end":"2026-07-21T13:23:35.529936Z","steps":["trace[1081624953] 'read index received' (duration: 451.331763ms)","trace[1081624953] 'applied index is now lower than readState.Index' (duration: 179.145663ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:23:35.530144Z","caller":"traceutil/trace.go:171","msg":"trace[592104047] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1399; }","duration":"630.729974ms","start":"2026-07-21T13:23:34.899404Z","end":"2026-07-21T13:23:35.530135Z","steps":["trace[592104047] 'agreement among raft nodes before linearized reading' (duration: 630.59917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:35.530173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:34.899385Z","time spent":"630.780566ms","remote":"127.0.0.1:46390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T13:23:35.530287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.010513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T13:23:35.530306Z","caller":"traceutil/trace.go:171","msg":"trace[777773861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1399; }","duration":"198.064544ms","start":"2026-07-21T13:23:35.332234Z","end":"2026-07-21T13:23:35.530299Z","steps":["trace[777773861] 'agreement among raft nodes before linearized reading' (duration: 197.983701ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:35.530304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.317757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-21T13:23:35.530336Z","caller":"traceutil/trace.go:171","msg":"trace[646399443] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1399; }","duration":"504.376259ms","start":"2026-07-21T13:23:35.025948Z","end":"2026-07-21T13:23:35.530325Z","steps":["trace[646399443] 'agreement among raft nodes before linearized reading' (duration: 504.254655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:35.530359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:23:35.025933Z","time spent":"504.418129ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T13:23:35.530444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.362773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-21T13:23:35.530461Z","caller":"traceutil/trace.go:171","msg":"trace[995689072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1399; }","duration":"265.407185ms","start":"2026-07-21T13:23:35.265048Z","end":"2026-07-21T13:23:35.530455Z","steps":["trace[995689072] 'agreement among raft nodes before linearized reading' (duration: 265.358254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:23:35.530459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.668445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:23:35.530488Z","caller":"traceutil/trace.go:171","msg":"trace[856539623] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1399; }","duration":"249.748098ms","start":"2026-07-21T13:23:35.280732Z","end":"2026-07-21T13:23:35.53048Z","steps":["trace[856539623] 'agreement among raft nodes before linearized reading' (duration: 249.656925ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:24:29.730198Z","caller":"traceutil/trace.go:171","msg":"trace[521171188] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"542.815343ms","start":"2026-07-21T13:24:29.187355Z","end":"2026-07-21T13:24:29.730171Z","steps":["trace[521171188] 'process raft request' (duration: 542.640447ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:29.730379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.187338Z","time spent":"542.949316ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:24:30.329779Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387970977703506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:24:30.830182Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13559387970977703506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T13:24:31.124479Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.345349692s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T13:24:31.12506Z","caller":"traceutil/trace.go:171","msg":"trace[579505581] transaction","detail":"{read_only:false; response_revision:1648; number_of_response:1; }","duration":"1.346033142s","start":"2026-07-21T13:24:29.779006Z","end":"2026-07-21T13:24:31.125039Z","steps":["trace[579505581] 'process raft request' (duration: 1.345883298s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.125148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.778985Z","time spent":"1.346102945s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:24:31.129412Z","caller":"traceutil/trace.go:171","msg":"trace[1684688788] linearizableReadLoop","detail":"{readStateIndex:1718; appliedIndex:1716; }","duration":"1.300258925s","start":"2026-07-21T13:24:29.829144Z","end":"2026-07-21T13:24:31.129403Z","steps":["trace[1684688788] 'read index received' (duration: 1.295701782s)","trace[1684688788] 'applied index is now lower than readState.Index' (duration: 4.556643ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:24:31.129515Z","caller":"traceutil/trace.go:171","msg":"trace[1413447265] transaction","detail":"{read_only:false; response_revision:1649; number_of_response:1; }","duration":"915.163719ms","start":"2026-07-21T13:24:30.214344Z","end":"2026-07-21T13:24:31.129508Z","steps":["trace[1413447265] 'process raft request' (duration: 915.010324ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.129555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:30.214327Z","time spent":"915.202839ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:24:31.129678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.230263233s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:24:31.129735Z","caller":"traceutil/trace.go:171","msg":"trace[1805543602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1649; }","duration":"1.230367546s","start":"2026-07-21T13:24:29.899351Z","end":"2026-07-21T13:24:31.129719Z","steps":["trace[1805543602] 'agreement among raft nodes before linearized reading' (duration: 1.230260433s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.129736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.319634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:24:31.129763Z","caller":"traceutil/trace.go:171","msg":"trace[1662517700] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1649; }","duration":"772.366485ms","start":"2026-07-21T13:24:30.357388Z","end":"2026-07-21T13:24:31.129755Z","steps":["trace[1662517700] 'agreement among raft nodes before linearized reading' (duration: 772.324473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.129763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.899332Z","time spent":"1.230426618s","remote":"127.0.0.1:46402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T13:24:31.129782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:30.357374Z","time spent":"772.402775ms","remote":"127.0.0.1:46534","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:24:31.129924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.300781459s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-21T13:24:31.129943Z","caller":"traceutil/trace.go:171","msg":"trace[2063553652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1649; }","duration":"1.300839281s","start":"2026-07-21T13:24:29.829098Z","end":"2026-07-21T13:24:31.129937Z","steps":["trace[2063553652] 'agreement among raft nodes before linearized reading' (duration: 1.300766749s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.129953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127614934s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-21T13:24:31.12996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.829077Z","time spent":"1.300876663s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-21T13:24:31.129969Z","caller":"traceutil/trace.go:171","msg":"trace[1302025765] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1649; }","duration":"1.127665556s","start":"2026-07-21T13:24:30.002299Z","end":"2026-07-21T13:24:31.129964Z","steps":["trace[1302025765] 'agreement among raft nodes before linearized reading' (duration: 1.127590733s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.129984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:30.00228Z","time spent":"1.127700996s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T13:24:31.130062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.822308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-07-21T13:24:31.130083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.152741213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-21T13:24:31.130081Z","caller":"traceutil/trace.go:171","msg":"trace[55745439] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1649; }","duration":"344.856138ms","start":"2026-07-21T13:24:30.785218Z","end":"2026-07-21T13:24:31.130074Z","steps":["trace[55745439] 'agreement among raft nodes before linearized reading' (duration: 344.805726ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:24:31.130097Z","caller":"traceutil/trace.go:171","msg":"trace[1996226493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1649; }","duration":"1.152793894s","start":"2026-07-21T13:24:29.977299Z","end":"2026-07-21T13:24:31.130093Z","steps":["trace[1996226493] 'agreement among raft nodes before linearized reading' (duration: 1.152752743s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.130127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:30.785211Z","time spent":"344.89507ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":559,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-07-21T13:24:31.130134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.977281Z","time spent":"1.152845045s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T13:24:31.130198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.031911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T13:24:31.130214Z","caller":"traceutil/trace.go:171","msg":"trace[955866143] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1649; }","duration":"398.050242ms","start":"2026-07-21T13:24:30.732159Z","end":"2026-07-21T13:24:31.130209Z","steps":["trace[955866143] 'agreement among raft nodes before linearized reading' (duration: 398.009401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.130229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:30.732148Z","time spent":"398.076962ms","remote":"127.0.0.1:46770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":17,"response size":31,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-21T13:24:31.130241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.180064116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T13:24:31.130256Z","caller":"traceutil/trace.go:171","msg":"trace[1398140201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1649; }","duration":"1.180113436s","start":"2026-07-21T13:24:29.950138Z","end":"2026-07-21T13:24:31.130251Z","steps":["trace[1398140201] 'agreement among raft nodes before linearized reading' (duration: 1.180062915s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.130269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.950098Z","time spent":"1.18016715s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T13:24:31.130353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.214845065s","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-07-21T13:24:31.130366Z","caller":"traceutil/trace.go:171","msg":"trace[435598378] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1649; }","duration":"1.214889077s","start":"2026-07-21T13:24:29.915473Z","end":"2026-07-21T13:24:31.130362Z","steps":["trace[435598378] 'agreement among raft nodes before linearized reading' (duration: 1.214851205s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:31.130379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:29.915458Z","time spent":"1.214916918s","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-21T13:24:31.749427Z","caller":"traceutil/trace.go:171","msg":"trace[1361657184] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"196.663996ms","start":"2026-07-21T13:24:31.552742Z","end":"2026-07-21T13:24:31.749406Z","steps":["trace[1361657184] 'process raft request' (duration: 188.032325ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:24:33.068521Z","caller":"traceutil/trace.go:171","msg":"trace[340435077] transaction","detail":"{read_only:false; response_revision:1658; number_of_response:1; }","duration":"774.717933ms","start":"2026-07-21T13:24:32.293783Z","end":"2026-07-21T13:24:33.068501Z","steps":["trace[340435077] 'process raft request' (duration: 774.591669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:33.068659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:24:32.293768Z","time spent":"774.818075ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T13:24:33.170317Z","caller":"traceutil/trace.go:171","msg":"trace[970973432] linearizableReadLoop","detail":"{readStateIndex:1728; appliedIndex:1727; }","duration":"101.283938ms","start":"2026-07-21T13:24:33.068999Z","end":"2026-07-21T13:24:33.170283Z","steps":["trace[970973432] 'read index received' (duration: 61.791412ms)","trace[970973432] 'applied index is now lower than readState.Index' (duration: 39.491056ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T13:24:33.170507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.315636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T13:24:33.170521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.522523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-21T13:24:33.170569Z","caller":"traceutil/trace.go:171","msg":"trace[5017456] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1658; }","duration":"105.398789ms","start":"2026-07-21T13:24:33.065154Z","end":"2026-07-21T13:24:33.170553Z","steps":["trace[5017456] 'agreement among raft nodes before linearized reading' (duration: 105.278735ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:24:33.170601Z","caller":"traceutil/trace.go:171","msg":"trace[2124255525] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1658; }","duration":"103.633077ms","start":"2026-07-21T13:24:33.066948Z","end":"2026-07-21T13:24:33.170581Z","steps":["trace[2124255525] 'agreement among raft nodes before linearized reading' (duration: 103.388681ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:24:33.18878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.281603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:24:33.188931Z","caller":"traceutil/trace.go:171","msg":"trace[1552160162] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1659; }","duration":"117.453168ms","start":"2026-07-21T13:24:33.071457Z","end":"2026-07-21T13:24:33.18891Z","steps":["trace[1552160162] 'agreement among raft nodes before linearized reading' (duration: 117.252512ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:24:43.358013Z","caller":"traceutil/trace.go:171","msg":"trace[1049637838] transaction","detail":"{read_only:false; response_revision:1705; number_of_response:1; }","duration":"143.986409ms","start":"2026-07-21T13:24:43.213995Z","end":"2026-07-21T13:24:43.357981Z","steps":["trace[1049637838] 'process raft request' (duration: 104.423231ms)","trace[1049637838] 'compare' (duration: 39.311391ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:24:43.358564Z","caller":"traceutil/trace.go:171","msg":"trace[1661695464] transaction","detail":"{read_only:false; response_revision:1706; number_of_response:1; }","duration":"129.194419ms","start":"2026-07-21T13:24:43.229328Z","end":"2026-07-21T13:24:43.358522Z","steps":["trace[1661695464] 'process raft request' (duration: 128.54738ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:08.684281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.163927ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-21T13:26:08.684412Z","caller":"traceutil/trace.go:171","msg":"trace[1929349930] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2098; }","duration":"412.332622ms","start":"2026-07-21T13:26:08.272049Z","end":"2026-07-21T13:26:08.684382Z","steps":["trace[1929349930] 'agreement among raft nodes before linearized reading' (duration: 412.046203ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:08.684473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:26:08.272024Z","time spent":"412.432295ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-21T13:26:08.684658Z","caller":"traceutil/trace.go:171","msg":"trace[1789392068] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"411.745897ms","start":"2026-07-21T13:26:08.272099Z","end":"2026-07-21T13:26:08.683845Z","steps":["trace[1789392068] 'read index received' (duration: 409.984715ms)","trace[1789392068] 'applied index is now lower than readState.Index' (duration: 1.759301ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T13:26:08.684724Z","caller":"traceutil/trace.go:171","msg":"trace[1500550339] transaction","detail":"{read_only:false; response_revision:2098; number_of_response:1; }","duration":"472.098791ms","start":"2026-07-21T13:26:08.211857Z","end":"2026-07-21T13:26:08.683956Z","steps":["trace[1500550339] 'process raft request' (duration: 470.397201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:08.684959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T13:26:08.211841Z","time spent":"472.991616ms","remote":"127.0.0.1:46700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T13:26:08.685094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.884884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-07-21T13:26:08.685211Z","caller":"traceutil/trace.go:171","msg":"trace[1661154451] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2098; }","duration":"297.051349ms","start":"2026-07-21T13:26:08.388135Z","end":"2026-07-21T13:26:08.685186Z","steps":["trace[1661154451] 'agreement among raft nodes before linearized reading' (duration: 296.76411ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:08.68538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.021401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-07-21T13:26:08.685455Z","caller":"traceutil/trace.go:171","msg":"trace[1820395099] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2098; }","duration":"147.134934ms","start":"2026-07-21T13:26:08.538299Z","end":"2026-07-21T13:26:08.685434Z","steps":["trace[1820395099] 'agreement among raft nodes before linearized reading' (duration: 146.886627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:09.051873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.912733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:26:09.051943Z","caller":"traceutil/trace.go:171","msg":"trace[242096024] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2101; }","duration":"124.040436ms","start":"2026-07-21T13:26:08.927883Z","end":"2026-07-21T13:26:09.051923Z","steps":["trace[242096024] 'count revisions from in-memory index tree' (duration: 123.826611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T13:26:09.052095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.458399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T13:26:09.052143Z","caller":"traceutil/trace.go:171","msg":"trace[1565461312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2101; }","duration":"152.544381ms","start":"2026-07-21T13:26:08.899586Z","end":"2026-07-21T13:26:09.052131Z","steps":["trace[1565461312] 'range keys from in-memory index tree' (duration: 152.372496ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T13:28:09.077505Z","caller":"traceutil/trace.go:171","msg":"trace[1613205706] transaction","detail":"{read_only:false; response_revision:2678; number_of_response:1; }","duration":"104.553482ms","start":"2026-07-21T13:28:08.972926Z","end":"2026-07-21T13:28:09.07748Z","steps":["trace[1613205706] 'process raft request' (duration: 104.442669ms)"],"step_count":1}