{"level":"warn","ts":"2026-02-07T01:17:17.586961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T01:17:17.58705Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.70:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.70:2380","--initial-cluster=instance=https://162.253.55.70:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.70:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.70: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-02-07T01:17:17.587118Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-07T01:17:17.587982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-07T01:17:17.588009Z","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-02-07T01:17:17.589492Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"]} {"level":"info","ts":"2026-02-07T01:17:17.592389Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.70: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-02-07T01:17:17.602394Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.727343ms"} {"level":"info","ts":"2026-02-07T01:17:17.63227Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"23887246632736aa","cluster-id":"62cfeca3ef46d3ef"} {"level":"info","ts":"2026-02-07T01:17:17.632425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=()"} {"level":"info","ts":"2026-02-07T01:17:17.632494Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 0"} {"level":"info","ts":"2026-02-07T01:17:17.632559Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 23887246632736aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-07T01:17:17.632616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became follower at term 1"} {"level":"info","ts":"2026-02-07T01:17:17.632718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"warn","ts":"2026-02-07T01:17:17.647989Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-07T01:17:17.652639Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-07T01:17:17.654418Z","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-02-07T01:17:17.657637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"23887246632736aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-07T01:17:17.659342Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"23887246632736aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-07T01:17:17.659637Z","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-02-07T01:17:17.659709Z","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-02-07T01:17:17.662539Z","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-02-07T01:17:17.6629Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa switched to configuration voters=(2560422034796918442)"} {"level":"info","ts":"2026-02-07T01:17:17.663038Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","added-peer-id":"23887246632736aa","added-peer-peer-urls":["https://162.253.55.70:2380"]} {"level":"info","ts":"2026-02-07T01:17:17.665696Z","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-02-07T01:17:17.66588Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"23887246632736aa","initial-advertise-peer-urls":["https://162.253.55.70:2380"],"listen-peer-urls":["https://162.253.55.70:2380"],"advertise-client-urls":["https://162.253.55.70:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.70:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-07T01:17:17.665923Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-07T01:17:17.66607Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-07T01:17:17.666118Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.70:2380"} {"level":"info","ts":"2026-02-07T01:17:18.432986Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa is starting a new election at term 1"} {"level":"info","ts":"2026-02-07T01:17:18.433141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became pre-candidate at term 1"} {"level":"info","ts":"2026-02-07T01:17:18.433199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgPreVoteResp from 23887246632736aa at term 1"} {"level":"info","ts":"2026-02-07T01:17:18.433257Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became candidate at term 2"} {"level":"info","ts":"2026-02-07T01:17:18.433297Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa received MsgVoteResp from 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-07T01:17:18.433355Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"23887246632736aa became leader at term 2"} {"level":"info","ts":"2026-02-07T01:17:18.433395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 23887246632736aa elected leader 23887246632736aa at term 2"} {"level":"info","ts":"2026-02-07T01:17:18.435184Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:17:18.43841Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"23887246632736aa","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.70:2379]}","request-path":"/0/members/23887246632736aa/attributes","cluster-id":"62cfeca3ef46d3ef","publish-timeout":"7s"} {"level":"info","ts":"2026-02-07T01:17:18.438561Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:17:18.438891Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-07T01:17:18.43905Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-07T01:17:18.439091Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"62cfeca3ef46d3ef","local-member-id":"23887246632736aa","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:17:18.439127Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-07T01:17:18.43919Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:17:18.439248Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-07T01:17:18.441897Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.70:2379"} {"level":"info","ts":"2026-02-07T01:17:18.442547Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-07T01:17:33.856044Z","caller":"traceutil/trace.go:171","msg":"trace[236124124] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"547.085567ms","start":"2026-02-07T01:17:33.308939Z","end":"2026-02-07T01:17:33.856025Z","steps":["trace[236124124] 'process raft request' (duration: 546.984275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:33.856582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.308925Z","time spent":"547.19688ms","remote":"127.0.0.1:55586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4579,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:17:34.840046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.616588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:17:34.840324Z","caller":"traceutil/trace.go:171","msg":"trace[1500825992] linearizableReadLoop","detail":"{readStateIndex:387; appliedIndex:386; }","duration":"1.13090946s","start":"2026-02-07T01:17:33.709398Z","end":"2026-02-07T01:17:34.840307Z","steps":["trace[1500825992] 'read index received' (duration: 147.034288ms)","trace[1500825992] 'applied index is now lower than readState.Index' (duration: 983.874262ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:17:34.84041Z","caller":"traceutil/trace.go:171","msg":"trace[936639504] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"1.418253511s","start":"2026-02-07T01:17:33.422146Z","end":"2026-02-07T01:17:34.840399Z","steps":["trace[936639504] 'process raft request' (duration: 1.21305158s)","trace[936639504] 'compare' (duration: 204.463215ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:17:34.840468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.422125Z","time spent":"1.418310122s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:17:34.840785Z","caller":"traceutil/trace.go:171","msg":"trace[292381563] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"1.120387141s","start":"2026-02-07T01:17:33.720388Z","end":"2026-02-07T01:17:34.840775Z","steps":["trace[292381563] 'process raft request' (duration: 1.119838529s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.840898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.720368Z","time spent":"1.120443783s","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4047,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:17:34.841056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.131691847s","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-02-07T01:17:34.841087Z","caller":"traceutil/trace.go:171","msg":"trace[1965530660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:376; }","duration":"1.13174762s","start":"2026-02-07T01:17:33.709329Z","end":"2026-02-07T01:17:34.841077Z","steps":["trace[1965530660] 'agreement among raft nodes before linearized reading' (duration: 1.131689367s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.841118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.709316Z","time spent":"1.13179541s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T01:17:34.841259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056560949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4061"} {"level":"info","ts":"2026-02-07T01:17:34.841288Z","caller":"traceutil/trace.go:171","msg":"trace[1328020616] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:376; }","duration":"1.056616131s","start":"2026-02-07T01:17:33.784663Z","end":"2026-02-07T01:17:34.841279Z","steps":["trace[1328020616] 'agreement among raft nodes before linearized reading' (duration: 1.05656977s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.841314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.78465Z","time spent":"1.056658892s","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4083,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-07T01:17:34.841547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.615682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:17:34.841576Z","caller":"traceutil/trace.go:171","msg":"trace[1527273816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"381.688293ms","start":"2026-02-07T01:17:34.459879Z","end":"2026-02-07T01:17:34.841567Z","steps":["trace[1527273816] 'agreement among raft nodes before linearized reading' (duration: 381.645702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.841602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:34.459854Z","time spent":"381.742145ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:17:34.841731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.997319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T01:17:34.841758Z","caller":"traceutil/trace.go:171","msg":"trace[1754288751] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:376; }","duration":"733.07421ms","start":"2026-02-07T01:17:34.108675Z","end":"2026-02-07T01:17:34.841749Z","steps":["trace[1754288751] 'agreement among raft nodes before linearized reading' (duration: 733.030729ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.841783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:34.108649Z","time spent":"733.128252ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:17:34.841903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.811903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-07T01:17:34.841934Z","caller":"traceutil/trace.go:171","msg":"trace[481085489] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:376; }","duration":"748.899596ms","start":"2026-02-07T01:17:34.093027Z","end":"2026-02-07T01:17:34.841926Z","steps":["trace[481085489] 'agreement among raft nodes before linearized reading' (duration: 748.852665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.841962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:34.09299Z","time spent":"748.963338ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:17:34.842078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"817.38062ms","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-02-07T01:17:34.842105Z","caller":"traceutil/trace.go:171","msg":"trace[262068325] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:376; }","duration":"817.442242ms","start":"2026-02-07T01:17:34.024654Z","end":"2026-02-07T01:17:34.842096Z","steps":["trace[262068325] 'agreement among raft nodes before linearized reading' (duration: 817.400371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.842128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:34.024634Z","time spent":"817.488612ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:17:34.844392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.873601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5260"} {"level":"info","ts":"2026-02-07T01:17:34.84464Z","caller":"traceutil/trace.go:171","msg":"trace[949246129] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:376; }","duration":"983.138897ms","start":"2026-02-07T01:17:33.861483Z","end":"2026-02-07T01:17:34.844622Z","steps":["trace[949246129] 'agreement among raft nodes before linearized reading' (duration: 982.80266ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:34.844804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:33.861471Z","time spent":"983.28411ms","remote":"127.0.0.1:55586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5282,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-02-07T01:17:51.165331Z","caller":"traceutil/trace.go:171","msg":"trace[2117740539] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"109.284582ms","start":"2026-02-07T01:17:51.056028Z","end":"2026-02-07T01:17:51.165313Z","steps":["trace[2117740539] 'read index received' (duration: 74.012636ms)","trace[2117740539] 'applied index is now lower than readState.Index' (duration: 35.271226ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:17:51.165424Z","caller":"traceutil/trace.go:171","msg":"trace[1456251849] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"156.377857ms","start":"2026-02-07T01:17:51.009039Z","end":"2026-02-07T01:17:51.165417Z","steps":["trace[1456251849] 'process raft request' (duration: 121.04438ms)","trace[1456251849] 'compare' (duration: 35.139723ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:17:51.165593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.555979ms","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-02-07T01:17:51.165637Z","caller":"traceutil/trace.go:171","msg":"trace[856356203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"109.64337ms","start":"2026-02-07T01:17:51.055982Z","end":"2026-02-07T01:17:51.165625Z","steps":["trace[856356203] 'agreement among raft nodes before linearized reading' (duration: 109.548878ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:52.009087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.451737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.70\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-07T01:17:52.009141Z","caller":"traceutil/trace.go:171","msg":"trace[1982063907] range","detail":"{range_begin:/registry/masterleases/162.253.55.70; range_end:; response_count:1; response_revision:483; }","duration":"560.535049ms","start":"2026-02-07T01:17:51.448593Z","end":"2026-02-07T01:17:52.009128Z","steps":["trace[1982063907] 'range keys from in-memory index tree' (duration: 560.392906ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:52.009169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:17:51.44858Z","time spent":"560.58328ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":155,"request content":"key:\"/registry/masterleases/162.253.55.70\" "} {"level":"warn","ts":"2026-02-07T01:17:57.23895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.15617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:17:57.239018Z","caller":"traceutil/trace.go:171","msg":"trace[821602867] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"216.278363ms","start":"2026-02-07T01:17:57.022726Z","end":"2026-02-07T01:17:57.239004Z","steps":["trace[821602867] 'range keys from in-memory index tree' (duration: 216.078358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:57.239171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.084147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:17:57.239199Z","caller":"traceutil/trace.go:171","msg":"trace[1934816383] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"124.120848ms","start":"2026-02-07T01:17:57.115069Z","end":"2026-02-07T01:17:57.23919Z","steps":["trace[1934816383] 'range keys from in-memory index tree' (duration: 124.059066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:17:59.61552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.10913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:17:59.616781Z","caller":"traceutil/trace.go:171","msg":"trace[423762233] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"247.02318ms","start":"2026-02-07T01:17:59.369743Z","end":"2026-02-07T01:17:59.616766Z","steps":["trace[423762233] 'process raft request' (duration: 74.5698ms)","trace[423762233] 'compare' (duration: 170.759053ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:17:59.61913Z","caller":"traceutil/trace.go:171","msg":"trace[1826287802] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"247.789495ms","start":"2026-02-07T01:17:59.371329Z","end":"2026-02-07T01:17:59.619119Z","steps":["trace[1826287802] 'process raft request' (duration: 245.352162ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:17:59.619359Z","caller":"traceutil/trace.go:171","msg":"trace[2143768416] linearizableReadLoop","detail":"{readStateIndex:532; appliedIndex:530; }","duration":"184.783801ms","start":"2026-02-07T01:17:59.434567Z","end":"2026-02-07T01:17:59.61935Z","steps":["trace[2143768416] 'read index received' (duration: 9.750414ms)","trace[2143768416] 'applied index is now lower than readState.Index' (duration: 175.032047ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:17:59.619417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.841312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rtspf\" ","response":"range_response_count:1 size:4635"} {"level":"info","ts":"2026-02-07T01:17:59.61944Z","caller":"traceutil/trace.go:171","msg":"trace[1141990096] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-7fcd8d6ffd-rtspf; range_end:; response_count:1; response_revision:515; }","duration":"184.903804ms","start":"2026-02-07T01:17:59.43453Z","end":"2026-02-07T01:17:59.619434Z","steps":["trace[1141990096] 'agreement among raft nodes before linearized reading' (duration: 184.852463ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:17:59.621384Z","caller":"traceutil/trace.go:171","msg":"trace[263960424] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"177.108652ms","start":"2026-02-07T01:17:59.444267Z","end":"2026-02-07T01:17:59.621375Z","steps":["trace[263960424] 'process raft request' (duration: 174.418303ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:12.719929Z","caller":"traceutil/trace.go:171","msg":"trace[1677035389] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"201.243203ms","start":"2026-02-07T01:18:12.518668Z","end":"2026-02-07T01:18:12.719912Z","steps":["trace[1677035389] 'read index received' (duration: 201.13437ms)","trace[1677035389] 'applied index is now lower than readState.Index' (duration: 108.143µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:12.719958Z","caller":"traceutil/trace.go:171","msg":"trace[1927588418] transaction","detail":"{read_only:false; response_revision:672; number_of_response:1; }","duration":"201.704333ms","start":"2026-02-07T01:18:12.518232Z","end":"2026-02-07T01:18:12.719937Z","steps":["trace[1927588418] 'process raft request' (duration: 201.57358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:12.720077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.390116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-9c2ss\" ","response":"range_response_count:1 size:4823"} {"level":"info","ts":"2026-02-07T01:18:12.720097Z","caller":"traceutil/trace.go:171","msg":"trace[672541818] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-9c2ss; range_end:; response_count:1; response_revision:672; }","duration":"201.454027ms","start":"2026-02-07T01:18:12.518637Z","end":"2026-02-07T01:18:12.720091Z","steps":["trace[672541818] 'agreement among raft nodes before linearized reading' (duration: 201.340475ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:13.614089Z","caller":"traceutil/trace.go:171","msg":"trace[428298497] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"127.090933ms","start":"2026-02-07T01:18:13.48698Z","end":"2026-02-07T01:18:13.614071Z","steps":["trace[428298497] 'process raft request' (duration: 87.268278ms)","trace[428298497] 'compare' (duration: 39.530829ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:18.387032Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132578433844659,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T01:18:18.88763Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132578433844659,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-07T01:18:18.898053Z","caller":"traceutil/trace.go:171","msg":"trace[347823900] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"1.012079454s","start":"2026-02-07T01:18:17.885957Z","end":"2026-02-07T01:18:18.898036Z","steps":["trace[347823900] 'read index received' (duration: 1.011958872s)","trace[347823900] 'applied index is now lower than readState.Index' (duration: 119.972µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:18.898263Z","caller":"traceutil/trace.go:171","msg":"trace[2003634584] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.017285369s","start":"2026-02-07T01:18:17.88097Z","end":"2026-02-07T01:18:18.898256Z","steps":["trace[2003634584] 'process raft request' (duration: 1.016983522s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:18.898342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:17.880955Z","time spent":"1.01733016s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:18.898505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.012545215s","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-02-07T01:18:18.898528Z","caller":"traceutil/trace.go:171","msg":"trace[1562439864] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.012577146s","start":"2026-02-07T01:18:17.885942Z","end":"2026-02-07T01:18:18.898519Z","steps":["trace[1562439864] 'agreement among raft nodes before linearized reading' (duration: 1.012502794s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:18.898556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:17.885936Z","time spent":"1.012615657s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:18:18.898662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01048025s","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-02-07T01:18:18.898686Z","caller":"traceutil/trace.go:171","msg":"trace[1695283746] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:687; }","duration":"1.01051427s","start":"2026-02-07T01:18:17.888166Z","end":"2026-02-07T01:18:18.89868Z","steps":["trace[1695283746] 'agreement among raft nodes before linearized reading' (duration: 1.010461819s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:18.898702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:17.888159Z","time spent":"1.010539121s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T01:18:19.615492Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3939132578433844662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-07T01:18:20.033282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.989189ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:18:20.033349Z","caller":"traceutil/trace.go:171","msg":"trace[1851396082] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.131514209s","start":"2026-02-07T01:18:18.901823Z","end":"2026-02-07T01:18:20.033337Z","steps":["trace[1851396082] 'process raft request' (duration: 171.415637ms)","trace[1851396082] 'compare' (duration: 959.420946ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:20.033388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:18.901807Z","time spent":"1.131564059s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:20.073471Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.000288724s","expected-duration":"1s"} {"level":"info","ts":"2026-02-07T01:18:20.073659Z","caller":"traceutil/trace.go:171","msg":"trace[739422767] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"959.096148ms","start":"2026-02-07T01:18:19.11455Z","end":"2026-02-07T01:18:20.073646Z","steps":["trace[739422767] 'read index received' (duration: 959.003046ms)","trace[739422767] 'applied index is now lower than readState.Index' (duration: 92.512µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:20.073806Z","caller":"traceutil/trace.go:171","msg":"trace[126553636] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.169724628s","start":"2026-02-07T01:18:18.904075Z","end":"2026-02-07T01:18:20.0738Z","steps":["trace[126553636] 'process raft request' (duration: 1.169509683s)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:20.073863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:18.904066Z","time spent":"1.169753768s","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:20.073964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"959.415276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:20.07398Z","caller":"traceutil/trace.go:171","msg":"trace[258593967] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"959.461466ms","start":"2026-02-07T01:18:19.114513Z","end":"2026-02-07T01:18:20.073975Z","steps":["trace[258593967] 'agreement among raft nodes before linearized reading' (duration: 959.433476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:20.073993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:19.114496Z","time spent":"959.494628ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:18:20.074096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.417329ms","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-02-07T01:18:20.07411Z","caller":"traceutil/trace.go:171","msg":"trace[1302703967] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"571.45959ms","start":"2026-02-07T01:18:19.502646Z","end":"2026-02-07T01:18:20.074106Z","steps":["trace[1302703967] 'agreement among raft nodes before linearized reading' (duration: 571.407029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:20.074124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:19.50263Z","time spent":"571.49049ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:18:20.074224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.184275ms","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-02-07T01:18:20.074239Z","caller":"traceutil/trace.go:171","msg":"trace[1094919168] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"445.222286ms","start":"2026-02-07T01:18:19.629012Z","end":"2026-02-07T01:18:20.074235Z","steps":["trace[1094919168] 'agreement among raft nodes before linearized reading' (duration: 445.186275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:20.074251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:19.628997Z","time spent":"445.250476ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-07T01:18:24.400593Z","caller":"traceutil/trace.go:171","msg":"trace[630744108] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:729; }","duration":"315.27927ms","start":"2026-02-07T01:18:24.085292Z","end":"2026-02-07T01:18:24.400571Z","steps":["trace[630744108] 'read index received' (duration: 315.085126ms)","trace[630744108] 'applied index is now lower than readState.Index' (duration: 193.254µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:24.400862Z","caller":"traceutil/trace.go:171","msg":"trace[1268006620] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"352.815364ms","start":"2026-02-07T01:18:24.048035Z","end":"2026-02-07T01:18:24.40085Z","steps":["trace[1268006620] 'process raft request' (duration: 352.413815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.400968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.048025Z","time spent":"352.876475ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:24.401233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.935734ms","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-02-07T01:18:24.401274Z","caller":"traceutil/trace.go:171","msg":"trace[614181706] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:705; }","duration":"316.022626ms","start":"2026-02-07T01:18:24.085241Z","end":"2026-02-07T01:18:24.401263Z","steps":["trace[614181706] 'agreement among raft nodes before linearized reading' (duration: 315.908173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.401311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.085229Z","time spent":"316.074517ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T01:18:24.401489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.941188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:18:24.401528Z","caller":"traceutil/trace.go:171","msg":"trace[740468925] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:705; }","duration":"302.992598ms","start":"2026-02-07T01:18:24.098523Z","end":"2026-02-07T01:18:24.401516Z","steps":["trace[740468925] 'agreement among raft nodes before linearized reading' (duration: 302.909727ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.401562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.098514Z","time spent":"303.04167ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-07T01:18:24.401748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.01281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:24.401785Z","caller":"traceutil/trace.go:171","msg":"trace[1143397375] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:705; }","duration":"288.060481ms","start":"2026-02-07T01:18:24.113717Z","end":"2026-02-07T01:18:24.401777Z","steps":["trace[1143397375] 'agreement among raft nodes before linearized reading' (duration: 288.00687ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.401969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.954438ms","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-02-07T01:18:24.402004Z","caller":"traceutil/trace.go:171","msg":"trace[2085827527] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:705; }","duration":"302.997439ms","start":"2026-02-07T01:18:24.098999Z","end":"2026-02-07T01:18:24.401996Z","steps":["trace[2085827527] 'agreement among raft nodes before linearized reading' (duration: 302.912897ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.402036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.098995Z","time spent":"303.03571ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:18:24.717075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.981016ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:18:24.717144Z","caller":"traceutil/trace.go:171","msg":"trace[626386695] linearizableReadLoop","detail":"{readStateIndex:731; appliedIndex:730; }","duration":"308.223605ms","start":"2026-02-07T01:18:24.40891Z","end":"2026-02-07T01:18:24.717134Z","steps":["trace[626386695] 'read index received' (duration: 138.139266ms)","trace[626386695] 'applied index is now lower than readState.Index' (duration: 170.083549ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:24.717153Z","caller":"traceutil/trace.go:171","msg":"trace[1553830886] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"308.4815ms","start":"2026-02-07T01:18:24.408659Z","end":"2026-02-07T01:18:24.717141Z","steps":["trace[1553830886] 'process raft request' (duration: 138.381391ms)","trace[1553830886] 'compare' (duration: 169.893904ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:24.71718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.270345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:24.717195Z","caller":"traceutil/trace.go:171","msg":"trace[505911144] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:706; }","duration":"308.307176ms","start":"2026-02-07T01:18:24.408883Z","end":"2026-02-07T01:18:24.71719Z","steps":["trace[505911144] 'agreement among raft nodes before linearized reading' (duration: 308.278875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.717213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.408875Z","time spent":"308.334476ms","remote":"127.0.0.1:55436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:18:24.717219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.408639Z","time spent":"308.539451ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:24.750989Z","caller":"traceutil/trace.go:171","msg":"trace[1128419985] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"340.707338ms","start":"2026-02-07T01:18:24.410267Z","end":"2026-02-07T01:18:24.750974Z","steps":["trace[1128419985] 'process raft request' (duration: 340.680788ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.751085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.410263Z","time spent":"340.771509ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:24.751313Z","caller":"traceutil/trace.go:171","msg":"trace[1379219741] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"341.082626ms","start":"2026-02-07T01:18:24.410224Z","end":"2026-02-07T01:18:24.751307Z","steps":["trace[1379219741] 'process raft request' (duration: 340.638257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:24.751353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.410217Z","time spent":"341.113747ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:25.283801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.073101ms","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-02-07T01:18:25.283848Z","caller":"traceutil/trace.go:171","msg":"trace[1658064276] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:708; }","duration":"359.165313ms","start":"2026-02-07T01:18:24.924672Z","end":"2026-02-07T01:18:25.283837Z","steps":["trace[1658064276] 'range keys from in-memory index tree' (duration: 358.804936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:25.283869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.924656Z","time spent":"359.207974ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:18:25.28398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.833616ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:25.283993Z","caller":"traceutil/trace.go:171","msg":"trace[392727464] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:708; }","duration":"353.867227ms","start":"2026-02-07T01:18:24.930122Z","end":"2026-02-07T01:18:25.283989Z","steps":["trace[392727464] 'count revisions from in-memory index tree' (duration: 353.806476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:25.284005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:24.930115Z","time spent":"353.886437ms","remote":"127.0.0.1:55792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":27,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-07T01:18:25.284111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.833931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:25.284124Z","caller":"traceutil/trace.go:171","msg":"trace[248881602] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"168.847011ms","start":"2026-02-07T01:18:25.115273Z","end":"2026-02-07T01:18:25.28412Z","steps":["trace[248881602] 'range keys from in-memory index tree' (duration: 168.781659ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:26.354025Z","caller":"traceutil/trace.go:171","msg":"trace[1609850217] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"532.442482ms","start":"2026-02-07T01:18:25.821561Z","end":"2026-02-07T01:18:26.354004Z","steps":["trace[1609850217] 'process raft request' (duration: 532.35651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.354122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:25.821539Z","time spent":"532.530173ms","remote":"127.0.0.1:55582","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4768,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:26.584381Z","caller":"traceutil/trace.go:171","msg":"trace[1112284170] linearizableReadLoop","detail":"{readStateIndex:736; appliedIndex:735; }","duration":"719.154175ms","start":"2026-02-07T01:18:25.865199Z","end":"2026-02-07T01:18:26.584353Z","steps":["trace[1112284170] 'read index received' (duration: 489.188191ms)","trace[1112284170] 'applied index is now lower than readState.Index' (duration: 229.964964ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:26.584699Z","caller":"traceutil/trace.go:171","msg":"trace[122187225] transaction","detail":"{read_only:false; response_revision:711; number_of_response:1; }","duration":"754.935601ms","start":"2026-02-07T01:18:25.829751Z","end":"2026-02-07T01:18:26.584686Z","steps":["trace[122187225] 'process raft request' (duration: 702.08485ms)","trace[122187225] 'compare' (duration: 52.179907ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:26.584792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:25.829743Z","time spent":"754.985092ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:26.584987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.78174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:18:26.585019Z","caller":"traceutil/trace.go:171","msg":"trace[2145662810] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:711; }","duration":"719.81446ms","start":"2026-02-07T01:18:25.865195Z","end":"2026-02-07T01:18:26.58501Z","steps":["trace[2145662810] 'agreement among raft nodes before linearized reading' (duration: 719.751799ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.585048Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:25.865164Z","time spent":"719.876191ms","remote":"127.0.0.1:55842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":29,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-07T01:18:26.585071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.548852ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:26.585157Z","caller":"traceutil/trace.go:171","msg":"trace[2074330615] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:711; }","duration":"470.660814ms","start":"2026-02-07T01:18:26.114478Z","end":"2026-02-07T01:18:26.585139Z","steps":["trace[2074330615] 'agreement among raft nodes before linearized reading' (duration: 470.533001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.585198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:26.114464Z","time spent":"470.725796ms","remote":"127.0.0.1:55436","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:18:26.585478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.98994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-07T01:18:26.585542Z","caller":"traceutil/trace.go:171","msg":"trace[535503137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:711; }","duration":"172.097073ms","start":"2026-02-07T01:18:26.413436Z","end":"2026-02-07T01:18:26.585533Z","steps":["trace[535503137] 'agreement among raft nodes before linearized reading' (duration: 171.936749ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.887983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.911189ms","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-02-07T01:18:26.888042Z","caller":"traceutil/trace.go:171","msg":"trace[1428856255] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:712; }","duration":"167.048972ms","start":"2026-02-07T01:18:26.720979Z","end":"2026-02-07T01:18:26.888028Z","steps":["trace[1428856255] 'range keys from in-memory index tree' (duration: 166.786156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.8883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.175352ms","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-02-07T01:18:26.88832Z","caller":"traceutil/trace.go:171","msg":"trace[181585733] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:712; }","duration":"130.213022ms","start":"2026-02-07T01:18:26.758101Z","end":"2026-02-07T01:18:26.888314Z","steps":["trace[181585733] 'range keys from in-memory index tree' (duration: 130.11338ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:26.888423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.200853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-07T01:18:26.88844Z","caller":"traceutil/trace.go:171","msg":"trace[1591235914] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:712; }","duration":"130.217243ms","start":"2026-02-07T01:18:26.758218Z","end":"2026-02-07T01:18:26.888435Z","steps":["trace[1591235914] 'range keys from in-memory index tree' (duration: 130.12899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:27.101293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.706656ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-07T01:18:27.102459Z","caller":"traceutil/trace.go:171","msg":"trace[290225803] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"208.501873ms","start":"2026-02-07T01:18:26.893925Z","end":"2026-02-07T01:18:27.102427Z","steps":["trace[290225803] 'process raft request' (duration: 208.39417ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:27.106201Z","caller":"traceutil/trace.go:171","msg":"trace[527123895] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"209.594966ms","start":"2026-02-07T01:18:26.896597Z","end":"2026-02-07T01:18:27.106192Z","steps":["trace[527123895] 'process raft request' (duration: 209.572075ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:27.106414Z","caller":"traceutil/trace.go:171","msg":"trace[706020806] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"209.871533ms","start":"2026-02-07T01:18:26.896536Z","end":"2026-02-07T01:18:27.106407Z","steps":["trace[706020806] 'process raft request' (duration: 209.581925ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:37.766964Z","caller":"traceutil/trace.go:171","msg":"trace[1845103371] linearizableReadLoop","detail":"{readStateIndex:772; appliedIndex:771; }","duration":"174.468824ms","start":"2026-02-07T01:18:37.59247Z","end":"2026-02-07T01:18:37.766938Z","steps":["trace[1845103371] 'read index received' (duration: 174.29871ms)","trace[1845103371] 'applied index is now lower than readState.Index' (duration: 167.894µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:37.767065Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.574826ms","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-02-07T01:18:37.767082Z","caller":"traceutil/trace.go:171","msg":"trace[926380994] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:744; }","duration":"174.637418ms","start":"2026-02-07T01:18:37.592439Z","end":"2026-02-07T01:18:37.767076Z","steps":["trace[926380994] 'agreement among raft nodes before linearized reading' (duration: 174.567676ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:37.767229Z","caller":"traceutil/trace.go:171","msg":"trace[771762747] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"360.611186ms","start":"2026-02-07T01:18:37.406612Z","end":"2026-02-07T01:18:37.767223Z","steps":["trace[771762747] 'process raft request' (duration: 360.207517ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:37.767279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:37.406603Z","time spent":"360.638326ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:38.862072Z","caller":"traceutil/trace.go:171","msg":"trace[1735232502] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"158.049673ms","start":"2026-02-07T01:18:38.704005Z","end":"2026-02-07T01:18:38.862054Z","steps":["trace[1735232502] 'process raft request' (duration: 157.929521ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:39.236474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.843885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:39.23654Z","caller":"traceutil/trace.go:171","msg":"trace[2024348127] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:745; }","duration":"119.962077ms","start":"2026-02-07T01:18:39.116563Z","end":"2026-02-07T01:18:39.236525Z","steps":["trace[2024348127] 'range keys from in-memory index tree' (duration: 119.766903ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:39.785544Z","caller":"traceutil/trace.go:171","msg":"trace[1493931099] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"538.788865ms","start":"2026-02-07T01:18:39.246733Z","end":"2026-02-07T01:18:39.785522Z","steps":["trace[1493931099] 'process raft request' (duration: 538.658803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:39.785695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:39.246718Z","time spent":"538.897288ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:39.796389Z","caller":"traceutil/trace.go:171","msg":"trace[2078530563] linearizableReadLoop","detail":"{readStateIndex:776; appliedIndex:774; }","duration":"270.823715ms","start":"2026-02-07T01:18:39.52555Z","end":"2026-02-07T01:18:39.796374Z","steps":["trace[2078530563] 'read index received' (duration: 261.283534ms)","trace[2078530563] 'applied index is now lower than readState.Index' (duration: 9.539301ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:39.79707Z","caller":"traceutil/trace.go:171","msg":"trace[1831524742] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"543.587151ms","start":"2026-02-07T01:18:39.253475Z","end":"2026-02-07T01:18:39.797062Z","steps":["trace[1831524742] 'process raft request' (duration: 542.794443ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:39.797254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:39.253464Z","time spent":"543.641471ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:18:39.797457Z","caller":"traceutil/trace.go:171","msg":"trace[461523865] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"543.847836ms","start":"2026-02-07T01:18:39.253602Z","end":"2026-02-07T01:18:39.79745Z","steps":["trace[461523865] 'process raft request' (duration: 542.745872ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:39.797501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:39.253598Z","time spent":"543.885626ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:39.797743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.188985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:18:39.7978Z","caller":"traceutil/trace.go:171","msg":"trace[1270375712] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:748; }","duration":"272.289147ms","start":"2026-02-07T01:18:39.525505Z","end":"2026-02-07T01:18:39.797794Z","steps":["trace[1270375712] 'agreement among raft nodes before linearized reading' (duration: 272.212855ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:39.92706Z","caller":"traceutil/trace.go:171","msg":"trace[1694770749] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"122.191918ms","start":"2026-02-07T01:18:39.804836Z","end":"2026-02-07T01:18:39.927028Z","steps":["trace[1694770749] 'process raft request' (duration: 36.525421ms)","trace[1694770749] 'compare' (duration: 85.542224ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:50.015832Z","caller":"traceutil/trace.go:171","msg":"trace[620738783] transaction","detail":"{read_only:false; response_revision:827; number_of_response:1; }","duration":"135.157171ms","start":"2026-02-07T01:18:49.880659Z","end":"2026-02-07T01:18:50.015816Z","steps":["trace[620738783] 'process raft request' (duration: 131.941103ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:50.018362Z","caller":"traceutil/trace.go:171","msg":"trace[149854850] transaction","detail":"{read_only:false; response_revision:828; number_of_response:1; }","duration":"136.248394ms","start":"2026-02-07T01:18:49.882093Z","end":"2026-02-07T01:18:50.018342Z","steps":["trace[149854850] 'process raft request' (duration: 136.146042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:51.792726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:51.451917Z","time spent":"340.805841ms","remote":"127.0.0.1:55476","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-07T01:18:51.793061Z","caller":"traceutil/trace.go:171","msg":"trace[862280574] linearizableReadLoop","detail":"{readStateIndex:863; appliedIndex:863; }","duration":"158.473824ms","start":"2026-02-07T01:18:51.634575Z","end":"2026-02-07T01:18:51.793049Z","steps":["trace[862280574] 'read index received' (duration: 158.470674ms)","trace[862280574] 'applied index is now lower than readState.Index' (duration: 2.34µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:51.793163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.572166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:18:51.793186Z","caller":"traceutil/trace.go:171","msg":"trace[1325780309] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:830; }","duration":"158.606847ms","start":"2026-02-07T01:18:51.634571Z","end":"2026-02-07T01:18:51.793178Z","steps":["trace[1325780309] 'agreement among raft nodes before linearized reading' (duration: 158.524435ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:51.96267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.029706ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:18:51.963027Z","caller":"traceutil/trace.go:171","msg":"trace[1179152118] transaction","detail":"{read_only:false; response_revision:832; number_of_response:1; }","duration":"169.310392ms","start":"2026-02-07T01:18:51.793699Z","end":"2026-02-07T01:18:51.96301Z","steps":["trace[1179152118] 'process raft request' (duration: 35.854067ms)","trace[1179152118] 'compare' (duration: 132.858212ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:52.587862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.396838ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:18:52.587927Z","caller":"traceutil/trace.go:171","msg":"trace[830072721] linearizableReadLoop","detail":"{readStateIndex:866; appliedIndex:865; }","duration":"617.902088ms","start":"2026-02-07T01:18:51.970013Z","end":"2026-02-07T01:18:52.587915Z","steps":["trace[830072721] 'read index received' (duration: 390.354066ms)","trace[830072721] 'applied index is now lower than readState.Index' (duration: 227.547162ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:52.587974Z","caller":"traceutil/trace.go:171","msg":"trace[1634696247] transaction","detail":"{read_only:false; response_revision:833; number_of_response:1; }","duration":"619.515931ms","start":"2026-02-07T01:18:51.968452Z","end":"2026-02-07T01:18:52.587968Z","steps":["trace[1634696247] 'process raft request' (duration: 391.965849ms)","trace[1634696247] 'compare' (duration: 227.198154ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:52.588002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:51.968433Z","time spent":"619.554691ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:18:52.588167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.663846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-07T01:18:52.588208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.502892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:18:52.588219Z","caller":"traceutil/trace.go:171","msg":"trace[1193073155] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:833; }","duration":"566.771548ms","start":"2026-02-07T01:18:52.021429Z","end":"2026-02-07T01:18:52.588201Z","steps":["trace[1193073155] 'agreement among raft nodes before linearized reading' (duration: 566.608715ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:52.588227Z","caller":"traceutil/trace.go:171","msg":"trace[1179909729] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:833; }","duration":"474.541233ms","start":"2026-02-07T01:18:52.11368Z","end":"2026-02-07T01:18:52.588222Z","steps":["trace[1179909729] 'agreement among raft nodes before linearized reading' (duration: 474.506472ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:52.588244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:52.113666Z","time spent":"474.575933ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:18:52.588271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:52.02141Z","time spent":"566.829299ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-07T01:18:52.588342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.328876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-07T01:18:52.588357Z","caller":"traceutil/trace.go:171","msg":"trace[1980754299] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:833; }","duration":"618.366277ms","start":"2026-02-07T01:18:51.969987Z","end":"2026-02-07T01:18:52.588354Z","steps":["trace[1980754299] 'agreement among raft nodes before linearized reading' (duration: 618.330816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:52.588369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:51.969976Z","time spent":"618.390417ms","remote":"127.0.0.1:55580","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":443,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-07T01:18:52.588421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.801274ms","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-02-07T01:18:52.588444Z","caller":"traceutil/trace.go:171","msg":"trace[158821349] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:833; }","duration":"562.843365ms","start":"2026-02-07T01:18:52.025596Z","end":"2026-02-07T01:18:52.588439Z","steps":["trace[158821349] 'agreement among raft nodes before linearized reading' (duration: 562.782294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:52.588457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:52.025586Z","time spent":"562.867506ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-07T01:18:52.58854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.047834ms","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-02-07T01:18:52.588552Z","caller":"traceutil/trace.go:171","msg":"trace[1555710754] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:833; }","duration":"567.072575ms","start":"2026-02-07T01:18:52.021476Z","end":"2026-02-07T01:18:52.588549Z","steps":["trace[1555710754] 'agreement among raft nodes before linearized reading' (duration: 567.037244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:18:52.588564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:18:52.021471Z","time spent":"567.090025ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-07T01:18:52.776296Z","caller":"traceutil/trace.go:171","msg":"trace[1010280645] linearizableReadLoop","detail":"{readStateIndex:869; appliedIndex:867; }","duration":"101.635653ms","start":"2026-02-07T01:18:52.674646Z","end":"2026-02-07T01:18:52.776281Z","steps":["trace[1010280645] 'read index received' (duration: 71.576299ms)","trace[1010280645] 'applied index is now lower than readState.Index' (duration: 30.058834ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:18:52.776427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.089828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-07T01:18:52.776448Z","caller":"traceutil/trace.go:171","msg":"trace[1168771563] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:836; }","duration":"178.130539ms","start":"2026-02-07T01:18:52.598313Z","end":"2026-02-07T01:18:52.776444Z","steps":["trace[1168771563] 'agreement among raft nodes before linearized reading' (duration: 178.024056ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:18:52.776665Z","caller":"traceutil/trace.go:171","msg":"trace[551108687] transaction","detail":"{read_only:false; response_revision:835; number_of_response:1; }","duration":"178.322572ms","start":"2026-02-07T01:18:52.598336Z","end":"2026-02-07T01:18:52.776659Z","steps":["trace[551108687] 'process raft request' (duration: 147.92544ms)","trace[551108687] 'compare' (duration: 29.902422ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:18:52.776738Z","caller":"traceutil/trace.go:171","msg":"trace[281280640] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"178.335562ms","start":"2026-02-07T01:18:52.59839Z","end":"2026-02-07T01:18:52.776725Z","steps":["trace[281280640] 'process raft request' (duration: 177.853252ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:19:02.044837Z","caller":"traceutil/trace.go:171","msg":"trace[1712993783] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"215.421034ms","start":"2026-02-07T01:19:01.8294Z","end":"2026-02-07T01:19:02.044821Z","steps":["trace[1712993783] 'process raft request' (duration: 215.325032ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:02.644656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.376791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:19:02.644714Z","caller":"traceutil/trace.go:171","msg":"trace[1191641410] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:860; }","duration":"531.466372ms","start":"2026-02-07T01:19:02.113228Z","end":"2026-02-07T01:19:02.644695Z","steps":["trace[1191641410] 'range keys from in-memory index tree' (duration: 531.32936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:02.644737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:02.113215Z","time spent":"531.517043ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T01:19:06.830262Z","caller":"traceutil/trace.go:171","msg":"trace[1221685717] transaction","detail":"{read_only:false; response_revision:871; number_of_response:1; }","duration":"123.517016ms","start":"2026-02-07T01:19:06.706718Z","end":"2026-02-07T01:19:06.830235Z","steps":["trace[1221685717] 'process raft request' (duration: 123.170048ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:07.197066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.666617ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-02-07T01:19:07.197704Z","caller":"traceutil/trace.go:171","msg":"trace[695763554] transaction","detail":"{read_only:false; response_revision:872; number_of_response:1; }","duration":"358.634487ms","start":"2026-02-07T01:19:06.839017Z","end":"2026-02-07T01:19:07.197652Z","steps":["trace[695763554] 'process raft request' (duration: 358.111476ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:07.197946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:06.839009Z","time spent":"358.77117ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-07T01:19:07.198327Z","caller":"traceutil/trace.go:171","msg":"trace[1263302342] linearizableReadLoop","detail":"{readStateIndex:907; appliedIndex:906; }","duration":"367.538575ms","start":"2026-02-07T01:19:06.830032Z","end":"2026-02-07T01:19:07.197571Z","steps":["trace[1263302342] 'read index received' (duration: 18.52699ms)","trace[1263302342] 'applied index is now lower than readState.Index' (duration: 349.010355ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:19:07.481623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.510527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:19:07.481695Z","caller":"traceutil/trace.go:171","msg":"trace[173029197] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:872; }","duration":"361.628129ms","start":"2026-02-07T01:19:07.120053Z","end":"2026-02-07T01:19:07.481681Z","steps":["trace[173029197] 'agreement among raft nodes before linearized reading' (duration: 79.408186ms)","trace[173029197] 'range keys from in-memory index tree' (duration: 282.108321ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:19:07.481722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:07.120035Z","time spent":"361.682891ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-07T01:19:07.481754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.671807ms","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-02-07T01:19:07.481802Z","caller":"traceutil/trace.go:171","msg":"trace[2137806621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:872; }","duration":"504.768299ms","start":"2026-02-07T01:19:06.97702Z","end":"2026-02-07T01:19:07.481788Z","steps":["trace[2137806621] 'agreement among raft nodes before linearized reading' (duration: 222.446594ms)","trace[2137806621] 'range keys from in-memory index tree' (duration: 282.191283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:19:07.481827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:06.976999Z","time spent":"504.821241ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-07T01:19:07.482004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.432897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-02-07T01:19:07.482038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.432669ms","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-02-07T01:19:07.482054Z","caller":"traceutil/trace.go:171","msg":"trace[1943097198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:872; }","duration":"654.462707ms","start":"2026-02-07T01:19:06.827555Z","end":"2026-02-07T01:19:07.482017Z","steps":["trace[1943097198] 'agreement among raft nodes before linearized reading' (duration: 370.820803ms)","trace[1943097198] 'range keys from in-memory index tree' (duration: 282.614003ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:19:07.482063Z","caller":"traceutil/trace.go:171","msg":"trace[452446840] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:872; }","duration":"647.46405ms","start":"2026-02-07T01:19:06.834592Z","end":"2026-02-07T01:19:07.482056Z","steps":["trace[452446840] 'agreement among raft nodes before linearized reading' (duration: 364.857769ms)","trace[452446840] 'range keys from in-memory index tree' (duration: 282.55819ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:19:07.482072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:06.827549Z","time spent":"654.519638ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-07T01:19:07.482082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:06.834587Z","time spent":"647.49187ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-07T01:19:13.118604Z","caller":"traceutil/trace.go:171","msg":"trace[1354720500] linearizableReadLoop","detail":"{readStateIndex:946; appliedIndex:945; }","duration":"212.569244ms","start":"2026-02-07T01:19:12.906024Z","end":"2026-02-07T01:19:13.118593Z","steps":["trace[1354720500] 'read index received' (duration: 212.463281ms)","trace[1354720500] 'applied index is now lower than readState.Index' (duration: 105.443µs)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:19:13.118747Z","caller":"traceutil/trace.go:171","msg":"trace[945141178] transaction","detail":"{read_only:false; response_revision:909; number_of_response:1; }","duration":"267.697679ms","start":"2026-02-07T01:19:12.851043Z","end":"2026-02-07T01:19:13.118741Z","steps":["trace[945141178] 'process raft request' (duration: 267.478013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:13.118852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.823411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:19:13.11887Z","caller":"traceutil/trace.go:171","msg":"trace[1757003038] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:909; }","duration":"212.870751ms","start":"2026-02-07T01:19:12.905993Z","end":"2026-02-07T01:19:13.118864Z","steps":["trace[1757003038] 'agreement among raft nodes before linearized reading' (duration: 212.83402ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:24.532051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.319665ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-07T01:19:24.532123Z","caller":"traceutil/trace.go:171","msg":"trace[2138435414] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1022; }","duration":"753.781653ms","start":"2026-02-07T01:19:23.778329Z","end":"2026-02-07T01:19:24.532111Z","steps":["trace[2138435414] 'read index received' (duration: 53.197492ms)","trace[2138435414] 'applied index is now lower than readState.Index' (duration: 700.583081ms)"],"step_count":2} {"level":"info","ts":"2026-02-07T01:19:24.532342Z","caller":"traceutil/trace.go:171","msg":"trace[1606267969] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"781.518729ms","start":"2026-02-07T01:19:23.750788Z","end":"2026-02-07T01:19:24.532306Z","steps":["trace[1606267969] 'process raft request' (duration: 80.883477ms)","trace[1606267969] 'compare' (duration: 699.938377ms)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:19:24.532389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:23.750775Z","time spent":"781.58863ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-07T01:19:24.532716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.385875ms","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:533"} {"level":"info","ts":"2026-02-07T01:19:24.532761Z","caller":"traceutil/trace.go:171","msg":"trace[2132722418] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:984; }","duration":"754.460817ms","start":"2026-02-07T01:19:23.778294Z","end":"2026-02-07T01:19:24.532754Z","steps":["trace[2132722418] 'agreement among raft nodes before linearized reading' (duration: 754.200382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:24.53278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:23.778279Z","time spent":"754.496868ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-02-07T01:19:24.53289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"668.367912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"info","ts":"2026-02-07T01:19:24.532907Z","caller":"traceutil/trace.go:171","msg":"trace[1768603128] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:984; }","duration":"668.417082ms","start":"2026-02-07T01:19:23.864484Z","end":"2026-02-07T01:19:24.532901Z","steps":["trace[1768603128] 'agreement among raft nodes before linearized reading' (duration: 668.369261ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:24.532924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:23.864467Z","time spent":"668.452984ms","remote":"127.0.0.1:55658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-07T01:19:24.533037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.524169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:19:24.533053Z","caller":"traceutil/trace.go:171","msg":"trace[1017711273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"417.564599ms","start":"2026-02-07T01:19:24.115483Z","end":"2026-02-07T01:19:24.533048Z","steps":["trace[1017711273] 'agreement among raft nodes before linearized reading' (duration: 417.538469ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:19:24.533069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-07T01:19:24.115468Z","time spent":"417.597631ms","remote":"127.0.0.1:55422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-07T01:22:32.215443Z","caller":"traceutil/trace.go:171","msg":"trace[1755636203] transaction","detail":"{read_only:false; response_revision:1781; number_of_response:1; }","duration":"204.586103ms","start":"2026-02-07T01:22:32.010841Z","end":"2026-02-07T01:22:32.215427Z","steps":["trace[1755636203] 'process raft request' (duration: 204.06343ms)"],"step_count":1} {"level":"info","ts":"2026-02-07T01:22:32.21504Z","caller":"traceutil/trace.go:171","msg":"trace[1428007888] linearizableReadLoop","detail":"{readStateIndex:1859; appliedIndex:1858; }","duration":"160.716667ms","start":"2026-02-07T01:22:32.054303Z","end":"2026-02-07T01:22:32.21502Z","steps":["trace[1428007888] 'read index received' (duration: 160.544792ms)","trace[1428007888] 'applied index is now lower than readState.Index' (duration: 169.095µs)"],"step_count":2} {"level":"warn","ts":"2026-02-07T01:22:32.217631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.317172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-07T01:22:32.217668Z","caller":"traceutil/trace.go:171","msg":"trace[453197588] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1781; }","duration":"163.424344ms","start":"2026-02-07T01:22:32.054231Z","end":"2026-02-07T01:22:32.217655Z","steps":["trace[453197588] 'agreement among raft nodes before linearized reading' (duration: 163.342363ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:22:32.217919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.67583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-02-07T01:22:32.217949Z","caller":"traceutil/trace.go:171","msg":"trace[1351045621] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1781; }","duration":"151.73017ms","start":"2026-02-07T01:22:32.06621Z","end":"2026-02-07T01:22:32.21794Z","steps":["trace[1351045621] 'agreement among raft nodes before linearized reading' (duration: 151.611548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-07T01:22:32.218118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.74346ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-07T01:22:32.218143Z","caller":"traceutil/trace.go:171","msg":"trace[783986999] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1781; }","duration":"101.769141ms","start":"2026-02-07T01:22:32.116366Z","end":"2026-02-07T01:22:32.218136Z","steps":["trace[783986999] 'agreement among raft nodes before linearized reading' (duration: 101.73071ms)"],"step_count":1}