{"level":"warn","ts":"2026-02-03T17:26:43.502679Z","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-03T17:26:43.502809Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.207:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.207:2380","--initial-cluster=instance=https://199.19.213.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.207: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-03T17:26:43.503046Z","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-03T17:26:43.503076Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-02-03T17:26:43.503162Z","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-03T17:26:43.503915Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"]} {"level":"info","ts":"2026-02-03T17:26:43.504096Z","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://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.207: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-03T17:26:43.51037Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.52936ms"} {"level":"info","ts":"2026-02-03T17:26:43.522351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"520ed656383c33a4","cluster-id":"21f34f1629612ac4"} {"level":"info","ts":"2026-02-03T17:26:43.522464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-03T17:26:43.522486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 0"} {"level":"info","ts":"2026-02-03T17:26:43.522579Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 520ed656383c33a4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-03T17:26:43.522733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became follower at term 1"} {"level":"info","ts":"2026-02-03T17:26:43.522835Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"warn","ts":"2026-02-03T17:26:43.534852Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-03T17:26:43.543538Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-03T17:26:43.55029Z","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-03T17:26:43.567324Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"520ed656383c33a4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-03T17:26:43.567725Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"520ed656383c33a4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-03T17:26:43.567907Z","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-03T17:26:43.567948Z","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-03T17:26:43.567981Z","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-03T17:26:43.568952Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 switched to configuration voters=(5912899026583040932)"} {"level":"info","ts":"2026-02-03T17:26:43.569122Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","added-peer-id":"520ed656383c33a4","added-peer-peer-urls":["https://199.19.213.207:2380"]} {"level":"info","ts":"2026-02-03T17:26:43.578053Z","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-03T17:26:43.578333Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"520ed656383c33a4","initial-advertise-peer-urls":["https://199.19.213.207:2380"],"listen-peer-urls":["https://199.19.213.207:2380"],"advertise-client-urls":["https://199.19.213.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-03T17:26:43.57839Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-03T17:26:43.578903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-02-03T17:26:43.578944Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.207:2380"} {"level":"info","ts":"2026-02-03T17:26:43.623374Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-03T17:26:43.623437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-03T17:26:43.623469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgPreVoteResp from 520ed656383c33a4 at term 1"} {"level":"info","ts":"2026-02-03T17:26:43.623542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became candidate at term 2"} {"level":"info","ts":"2026-02-03T17:26:43.623573Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 received MsgVoteResp from 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-02-03T17:26:43.623603Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"520ed656383c33a4 became leader at term 2"} {"level":"info","ts":"2026-02-03T17:26:43.623625Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 520ed656383c33a4 elected leader 520ed656383c33a4 at term 2"} {"level":"info","ts":"2026-02-03T17:26:43.626317Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:43.629356Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"520ed656383c33a4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.207:2379]}","request-path":"/0/members/520ed656383c33a4/attributes","cluster-id":"21f34f1629612ac4","publish-timeout":"7s"} {"level":"info","ts":"2026-02-03T17:26:43.629429Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:26:43.631016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-03T17:26:43.631237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-03T17:26:43.635882Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.207:2379"} {"level":"info","ts":"2026-02-03T17:26:43.649051Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21f34f1629612ac4","local-member-id":"520ed656383c33a4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:43.653228Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:43.653279Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-03T17:26:43.660688Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-03T17:26:43.660727Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-02-03T17:26:58.742461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.451988ms","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-03T17:26:58.742545Z","caller":"traceutil/trace.go:171","msg":"trace[1506752800] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:401; }","duration":"694.618431ms","start":"2026-02-03T17:26:58.047908Z","end":"2026-02-03T17:26:58.742527Z","steps":["trace[1506752800] 'range keys from in-memory index tree' (duration: 694.275334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.74259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:58.04789Z","time spent":"694.691203ms","remote":"127.0.0.1:59802","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-03T17:26:58.742839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.254136565s","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-03T17:26:58.742867Z","caller":"traceutil/trace.go:171","msg":"trace[704271032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:401; }","duration":"2.254195956s","start":"2026-02-03T17:26:56.488663Z","end":"2026-02-03T17:26:58.742859Z","steps":["trace[704271032] 'range keys from in-memory index tree' (duration: 2.254039453s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.742892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:56.488647Z","time spent":"2.254239527s","remote":"127.0.0.1:59802","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-03T17:26:58.743019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.560665909s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:26:58.743047Z","caller":"traceutil/trace.go:171","msg":"trace[993542195] range","detail":"{range_begin:/registry/masterleases/199.19.213.207; range_end:; response_count:1; response_revision:401; }","duration":"1.560726631s","start":"2026-02-03T17:26:57.182311Z","end":"2026-02-03T17:26:58.743038Z","steps":["trace[993542195] 'range keys from in-memory index tree' (duration: 1.560606718s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.74307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:57.182293Z","time spent":"1.560771361s","remote":"127.0.0.1:59578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.207\" "} {"level":"warn","ts":"2026-02-03T17:26:58.74328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.497106667s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:26:58.743806Z","caller":"traceutil/trace.go:171","msg":"trace[124597116] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:401; }","duration":"1.497632309s","start":"2026-02-03T17:26:57.246163Z","end":"2026-02-03T17:26:58.743795Z","steps":["trace[124597116] 'range keys from in-memory index tree' (duration: 1.496939844s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.744006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.544783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6898"} {"level":"info","ts":"2026-02-03T17:26:58.744033Z","caller":"traceutil/trace.go:171","msg":"trace[1358292612] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:401; }","duration":"860.601805ms","start":"2026-02-03T17:26:57.883424Z","end":"2026-02-03T17:26:58.744025Z","steps":["trace[1358292612] 'range keys from in-memory index tree' (duration: 860.484493ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.744054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:57.883409Z","time spent":"860.640105ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6920,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-02-03T17:26:58.744158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.292117ms","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-03T17:26:58.744177Z","caller":"traceutil/trace.go:171","msg":"trace[29909011] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:401; }","duration":"837.333387ms","start":"2026-02-03T17:26:57.906838Z","end":"2026-02-03T17:26:58.744171Z","steps":["trace[29909011] 'range keys from in-memory index tree' (duration: 837.244925ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.744192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:57.906825Z","time spent":"837.362908ms","remote":"127.0.0.1:59802","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-03T17:26:58.744269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"836.326374ms","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-03T17:26:58.744284Z","caller":"traceutil/trace.go:171","msg":"trace[579074019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:401; }","duration":"836.414975ms","start":"2026-02-03T17:26:57.907864Z","end":"2026-02-03T17:26:58.744279Z","steps":["trace[579074019] 'range keys from in-memory index tree' (duration: 836.244692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:26:58.744299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:26:57.907852Z","time spent":"836.443437ms","remote":"127.0.0.1:59802","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-03T17:27:15.166796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.241023ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:15.167903Z","caller":"traceutil/trace.go:171","msg":"trace[323458965] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"218.888913ms","start":"2026-02-03T17:27:14.948992Z","end":"2026-02-03T17:27:15.167881Z","steps":["trace[323458965] 'process raft request' (duration: 105.162834ms)","trace[323458965] 'compare' (duration: 111.09966ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:16.991113Z","caller":"traceutil/trace.go:171","msg":"trace[1484767151] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"147.835603ms","start":"2026-02-03T17:27:16.843263Z","end":"2026-02-03T17:27:16.991098Z","steps":["trace[1484767151] 'read index received' (duration: 147.644938ms)","trace[1484767151] 'applied index is now lower than readState.Index' (duration: 190.155µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:16.9912Z","caller":"traceutil/trace.go:171","msg":"trace[1640897222] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"170.537137ms","start":"2026-02-03T17:27:16.820637Z","end":"2026-02-03T17:27:16.991174Z","steps":["trace[1640897222] 'process raft request' (duration: 170.315362ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:16.991295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.016587ms","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-03T17:27:16.991314Z","caller":"traceutil/trace.go:171","msg":"trace[447152221] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:516; }","duration":"148.077877ms","start":"2026-02-03T17:27:16.843231Z","end":"2026-02-03T17:27:16.991309Z","steps":["trace[447152221] 'agreement among raft nodes before linearized reading' (duration: 147.937694ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:17.417636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.52415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-03T17:27:17.417683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.582169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:27:17.417708Z","caller":"traceutil/trace.go:171","msg":"trace[2076548645] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"246.631143ms","start":"2026-02-03T17:27:17.17106Z","end":"2026-02-03T17:27:17.417692Z","steps":["trace[2076548645] 'range keys from in-memory index tree' (duration: 246.379087ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:17.417748Z","caller":"traceutil/trace.go:171","msg":"trace[1404400766] range","detail":"{range_begin:/registry/masterleases/199.19.213.207; range_end:; response_count:1; response_revision:519; }","duration":"234.657902ms","start":"2026-02-03T17:27:17.183066Z","end":"2026-02-03T17:27:17.417724Z","steps":["trace[1404400766] 'range keys from in-memory index tree' (duration: 234.466627ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:18.035255Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721270872876664674,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:27:18.125353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:17.419202Z","time spent":"706.147312ms","remote":"127.0.0.1:59578","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-03T17:27:18.403763Z","caller":"traceutil/trace.go:171","msg":"trace[1380069229] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"277.315709ms","start":"2026-02-03T17:27:18.126429Z","end":"2026-02-03T17:27:18.403745Z","steps":["trace[1380069229] 'process raft request' (duration: 277.264968ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:18.403921Z","caller":"traceutil/trace.go:171","msg":"trace[310496616] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"982.278993ms","start":"2026-02-03T17:27:17.421623Z","end":"2026-02-03T17:27:18.403902Z","steps":["trace[310496616] 'process raft request' (duration: 957.121113ms)","trace[310496616] 'compare' (duration: 24.833353ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:18.403971Z","caller":"traceutil/trace.go:171","msg":"trace[115427349] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:537; }","duration":"869.678481ms","start":"2026-02-03T17:27:17.534283Z","end":"2026-02-03T17:27:18.403962Z","steps":["trace[115427349] 'read index received' (duration: 591.542514ms)","trace[115427349] 'applied index is now lower than readState.Index' (duration: 278.135197ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:18.40401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:17.421609Z","time spent":"982.352057ms","remote":"127.0.0.1:59802","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-03T17:27:18.404056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.757262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:18.404076Z","caller":"traceutil/trace.go:171","msg":"trace[171662137] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"869.837944ms","start":"2026-02-03T17:27:17.534232Z","end":"2026-02-03T17:27:18.40407Z","steps":["trace[171662137] 'agreement among raft nodes before linearized reading' (duration: 869.771463ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:18.404098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:17.53421Z","time spent":"869.881315ms","remote":"127.0.0.1:59554","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-03T17:27:24.877717Z","caller":"traceutil/trace.go:171","msg":"trace[1830941484] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"345.470014ms","start":"2026-02-03T17:27:24.532224Z","end":"2026-02-03T17:27:24.877694Z","steps":["trace[1830941484] 'read index received' (duration: 345.247669ms)","trace[1830941484] 'applied index is now lower than readState.Index' (duration: 221.145µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:24.877876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.631577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:24.877912Z","caller":"traceutil/trace.go:171","msg":"trace[1388451054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:534; }","duration":"345.706978ms","start":"2026-02-03T17:27:24.532192Z","end":"2026-02-03T17:27:24.877899Z","steps":["trace[1388451054] 'agreement among raft nodes before linearized reading' (duration: 345.599176ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:24.877946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:24.532178Z","time spent":"345.76073ms","remote":"127.0.0.1:59562","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-03T17:27:24.878018Z","caller":"traceutil/trace.go:171","msg":"trace[128300590] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"434.766198ms","start":"2026-02-03T17:27:24.443228Z","end":"2026-02-03T17:27:24.877994Z","steps":["trace[128300590] 'process raft request' (duration: 434.322128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:24.878146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:24.44321Z","time spent":"434.85828ms","remote":"127.0.0.1:59802","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-03T17:27:25.525957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.351531ms","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-03T17:27:25.526028Z","caller":"traceutil/trace.go:171","msg":"trace[1512341581] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"461.467735ms","start":"2026-02-03T17:27:25.064544Z","end":"2026-02-03T17:27:25.526011Z","steps":["trace[1512341581] 'range keys from in-memory index tree' (duration: 461.259459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:25.526081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.064531Z","time spent":"461.540186ms","remote":"127.0.0.1:59802","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-03T17:27:25.526512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.686999ms","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-03T17:27:25.526589Z","caller":"traceutil/trace.go:171","msg":"trace[1042196886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"468.844582ms","start":"2026-02-03T17:27:25.057725Z","end":"2026-02-03T17:27:25.52657Z","steps":["trace[1042196886] 'range keys from in-memory index tree' (duration: 468.583136ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:25.526638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.057705Z","time spent":"468.921254ms","remote":"127.0.0.1:59802","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-03T17:27:25.526455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.960716ms","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-03T17:27:25.526748Z","caller":"traceutil/trace.go:171","msg":"trace[1084819068] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"462.283443ms","start":"2026-02-03T17:27:25.064452Z","end":"2026-02-03T17:27:25.526735Z","steps":["trace[1084819068] 'range keys from in-memory index tree' (duration: 461.885634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:25.526788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.064432Z","time spent":"462.343234ms","remote":"127.0.0.1:59802","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-03T17:27:26.037793Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721270872876664720,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-03T17:27:26.069358Z","caller":"traceutil/trace.go:171","msg":"trace[342857362] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"538.181663ms","start":"2026-02-03T17:27:25.531157Z","end":"2026-02-03T17:27:26.069339Z","steps":["trace[342857362] 'process raft request' (duration: 538.02151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:26.069489Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.531142Z","time spent":"538.279146ms","remote":"127.0.0.1:59802","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-03T17:27:26.428652Z","caller":"traceutil/trace.go:171","msg":"trace[531149777] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"896.701243ms","start":"2026-02-03T17:27:25.531925Z","end":"2026-02-03T17:27:26.428626Z","steps":["trace[531149777] 'process raft request' (duration: 886.678215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:26.428941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.531907Z","time spent":"896.922729ms","remote":"127.0.0.1:59802","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-03T17:27:26.429074Z","caller":"traceutil/trace.go:171","msg":"trace[944835387] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:553; }","duration":"892.005497ms","start":"2026-02-03T17:27:25.537042Z","end":"2026-02-03T17:27:26.429048Z","steps":["trace[944835387] 'read index received' (duration: 532.26991ms)","trace[944835387] 'applied index is now lower than readState.Index' (duration: 359.732997ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:26.429214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.146421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:26.429251Z","caller":"traceutil/trace.go:171","msg":"trace[152422389] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"892.221013ms","start":"2026-02-03T17:27:25.53702Z","end":"2026-02-03T17:27:26.429241Z","steps":["trace[152422389] 'agreement among raft nodes before linearized reading' (duration: 892.140891ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:26.429288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.537011Z","time spent":"892.270064ms","remote":"127.0.0.1:59554","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-03T17:27:26.429689Z","caller":"traceutil/trace.go:171","msg":"trace[472826178] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"897.694585ms","start":"2026-02-03T17:27:25.531979Z","end":"2026-02-03T17:27:26.429674Z","steps":["trace[472826178] 'process raft request' (duration: 896.369706ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:26.429819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:25.531974Z","time spent":"897.808188ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:36.252931Z","caller":"traceutil/trace.go:171","msg":"trace[952702137] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"128.3406ms","start":"2026-02-03T17:27:36.124574Z","end":"2026-02-03T17:27:36.252914Z","steps":["trace[952702137] 'process raft request' (duration: 30.831799ms)","trace[952702137] 'compare' (duration: 97.320677ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:40.295794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.156163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-v76zd\" ","response":"range_response_count:1 size:4697"} {"level":"info","ts":"2026-02-03T17:27:40.295858Z","caller":"traceutil/trace.go:171","msg":"trace[1719000386] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-v76zd; range_end:; response_count:1; response_revision:680; }","duration":"164.252325ms","start":"2026-02-03T17:27:40.13159Z","end":"2026-02-03T17:27:40.295842Z","steps":["trace[1719000386] 'range keys from in-memory index tree' (duration: 164.04ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:40.492903Z","caller":"traceutil/trace.go:171","msg":"trace[2117124260] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"193.16735ms","start":"2026-02-03T17:27:40.299713Z","end":"2026-02-03T17:27:40.49288Z","steps":["trace[2117124260] 'process raft request' (duration: 193.041738ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.033377Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3721270872876665008,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-03T17:27:41.599123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.081406ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:41.59919Z","caller":"traceutil/trace.go:171","msg":"trace[1421504160] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:704; }","duration":"1.065976563s","start":"2026-02-03T17:27:40.533203Z","end":"2026-02-03T17:27:41.599179Z","steps":["trace[1421504160] 'read index received' (duration: 772.723642ms)","trace[1421504160] 'applied index is now lower than readState.Index' (duration: 293.252151ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:41.599335Z","caller":"traceutil/trace.go:171","msg":"trace[1332636756] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"1.29119634s","start":"2026-02-03T17:27:40.308129Z","end":"2026-02-03T17:27:41.599326Z","steps":["trace[1332636756] 'process raft request' (duration: 997.852008ms)","trace[1332636756] 'compare' (duration: 292.742459ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:41.599374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:40.308113Z","time spent":"1.291240982s","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4972,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:41.599496Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.06629143s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:41.599514Z","caller":"traceutil/trace.go:171","msg":"trace[250873230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:682; }","duration":"1.06633461s","start":"2026-02-03T17:27:40.533173Z","end":"2026-02-03T17:27:41.599507Z","steps":["trace[250873230] 'agreement among raft nodes before linearized reading' (duration: 1.066303569s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.599533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:40.533158Z","time spent":"1.066369362s","remote":"127.0.0.1:59554","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-03T17:27:41.599663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.055217848s","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-03T17:27:41.59968Z","caller":"traceutil/trace.go:171","msg":"trace[848568533] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:682; }","duration":"1.055245319s","start":"2026-02-03T17:27:40.544429Z","end":"2026-02-03T17:27:41.599675Z","steps":["trace[848568533] 'agreement among raft nodes before linearized reading' (duration: 1.055177767s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.599695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:40.544422Z","time spent":"1.055269659s","remote":"127.0.0.1:59802","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-03T17:27:41.599921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.279263ms","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-03T17:27:41.599942Z","caller":"traceutil/trace.go:171","msg":"trace[1565221653] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:682; }","duration":"335.334894ms","start":"2026-02-03T17:27:41.264599Z","end":"2026-02-03T17:27:41.599934Z","steps":["trace[1565221653] 'agreement among raft nodes before linearized reading' (duration: 335.277922ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.599958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.264579Z","time spent":"335.374955ms","remote":"127.0.0.1:59802","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-03T17:27:41.600077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.866544ms","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-03T17:27:41.600095Z","caller":"traceutil/trace.go:171","msg":"trace[722174762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:682; }","duration":"603.923544ms","start":"2026-02-03T17:27:40.996167Z","end":"2026-02-03T17:27:41.60009Z","steps":["trace[722174762] 'agreement among raft nodes before linearized reading' (duration: 603.873813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.60011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:40.996145Z","time spent":"603.961465ms","remote":"127.0.0.1:59802","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-03T17:27:41.600213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019317994s","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-03T17:27:41.600229Z","caller":"traceutil/trace.go:171","msg":"trace[1334976584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:682; }","duration":"1.019355825s","start":"2026-02-03T17:27:40.580868Z","end":"2026-02-03T17:27:41.600224Z","steps":["trace[1334976584] 'agreement among raft nodes before linearized reading' (duration: 1.019307754s)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.600244Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:40.580832Z","time spent":"1.019407956s","remote":"127.0.0.1:59802","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-03T17:27:41.960149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.227237ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:41.960243Z","caller":"traceutil/trace.go:171","msg":"trace[1527831694] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"331.896566ms","start":"2026-02-03T17:27:41.628333Z","end":"2026-02-03T17:27:41.960229Z","steps":["trace[1527831694] 'process raft request' (duration: 177.534836ms)","trace[1527831694] 'compare' (duration: 154.150495ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:41.960285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.628324Z","time spent":"331.941286ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1291,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:41.978451Z","caller":"traceutil/trace.go:171","msg":"trace[922841354] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"349.371582ms","start":"2026-02-03T17:27:41.629013Z","end":"2026-02-03T17:27:41.978384Z","steps":["trace[922841354] 'process raft request' (duration: 349.218638ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:41.978491Z","caller":"traceutil/trace.go:171","msg":"trace[1594850449] linearizableReadLoop","detail":"{readStateIndex:707; appliedIndex:705; }","duration":"349.428184ms","start":"2026-02-03T17:27:41.629045Z","end":"2026-02-03T17:27:41.978473Z","steps":["trace[1594850449] 'read index received' (duration: 176.83559ms)","trace[1594850449] 'applied index is now lower than readState.Index' (duration: 172.592084ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:41.978748Z","caller":"traceutil/trace.go:171","msg":"trace[1283196230] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"349.592508ms","start":"2026-02-03T17:27:41.62915Z","end":"2026-02-03T17:27:41.978742Z","steps":["trace[1283196230] 'process raft request' (duration: 349.178548ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.978773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.629006Z","time spent":"349.517936ms","remote":"127.0.0.1:59700","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":782,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:41.978892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.629145Z","time spent":"349.641839ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:41.979031Z","caller":"traceutil/trace.go:171","msg":"trace[1728125184] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"348.216587ms","start":"2026-02-03T17:27:41.630809Z","end":"2026-02-03T17:27:41.979025Z","steps":["trace[1728125184] 'process raft request' (duration: 347.543501ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.979106Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.630803Z","time spent":"348.286189ms","remote":"127.0.0.1:59802","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-03T17:27:41.979225Z","caller":"traceutil/trace.go:171","msg":"trace[1510824799] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"347.277555ms","start":"2026-02-03T17:27:41.631941Z","end":"2026-02-03T17:27:41.979219Z","steps":["trace[1510824799] 'process raft request' (duration: 346.439695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.979279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.631926Z","time spent":"347.334906ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:41.979369Z","caller":"traceutil/trace.go:171","msg":"trace[211342900] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"347.372807ms","start":"2026-02-03T17:27:41.631991Z","end":"2026-02-03T17:27:41.979363Z","steps":["trace[211342900] 'process raft request' (duration: 346.441405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.979648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.594146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:41.979694Z","caller":"traceutil/trace.go:171","msg":"trace[697009807] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:688; }","duration":"345.647057ms","start":"2026-02-03T17:27:41.634034Z","end":"2026-02-03T17:27:41.979681Z","steps":["trace[697009807] 'agreement among raft nodes before linearized reading' (duration: 345.596936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.979713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.631987Z","time spent":"347.674283ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:41.979736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.634028Z","time spent":"345.698628ms","remote":"127.0.0.1:59554","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-03T17:27:41.979593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.536229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-v76zd\" ","response":"range_response_count:1 size:4987"} {"level":"info","ts":"2026-02-03T17:27:41.979912Z","caller":"traceutil/trace.go:171","msg":"trace[100382122] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-v76zd; range_end:; response_count:1; response_revision:688; }","duration":"350.867046ms","start":"2026-02-03T17:27:41.629034Z","end":"2026-02-03T17:27:41.979901Z","steps":["trace[100382122] 'agreement among raft nodes before linearized reading' (duration: 350.449086ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:41.979976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:41.629031Z","time spent":"350.936477ms","remote":"127.0.0.1:59714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":5009,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-v76zd\" "} {"level":"info","ts":"2026-02-03T17:27:48.160446Z","caller":"traceutil/trace.go:171","msg":"trace[242242772] linearizableReadLoop","detail":"{readStateIndex:759; appliedIndex:758; }","duration":"152.254932ms","start":"2026-02-03T17:27:48.00817Z","end":"2026-02-03T17:27:48.160424Z","steps":["trace[242242772] 'read index received' (duration: 90.036492ms)","trace[242242772] 'applied index is now lower than readState.Index' (duration: 62.21775ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.160616Z","caller":"traceutil/trace.go:171","msg":"trace[1729967183] transaction","detail":"{read_only:false; response_revision:734; number_of_response:1; }","duration":"159.365574ms","start":"2026-02-03T17:27:48.001242Z","end":"2026-02-03T17:27:48.160608Z","steps":["trace[1729967183] 'process raft request' (duration: 97.059262ms)","trace[1729967183] 'compare' (duration: 62.007265ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:48.160796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.61309ms","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-03T17:27:48.160823Z","caller":"traceutil/trace.go:171","msg":"trace[1314014774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:734; }","duration":"152.665541ms","start":"2026-02-03T17:27:48.00815Z","end":"2026-02-03T17:27:48.160816Z","steps":["trace[1314014774] 'agreement among raft nodes before linearized reading' (duration: 152.572699ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.160947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.953645ms","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-03T17:27:48.16097Z","caller":"traceutil/trace.go:171","msg":"trace[2037232597] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:734; }","duration":"151.986286ms","start":"2026-02-03T17:27:48.008978Z","end":"2026-02-03T17:27:48.160964Z","steps":["trace[2037232597] 'agreement among raft nodes before linearized reading' (duration: 151.932105ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.161116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.794847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-02-03T17:27:48.161139Z","caller":"traceutil/trace.go:171","msg":"trace[306532694] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:734; }","duration":"145.838968ms","start":"2026-02-03T17:27:48.015295Z","end":"2026-02-03T17:27:48.161134Z","steps":["trace[306532694] 'agreement among raft nodes before linearized reading' (duration: 145.787147ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.838379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.603817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:27:48.838587Z","caller":"traceutil/trace.go:171","msg":"trace[1842597873] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:760; }","duration":"317.128751ms","start":"2026-02-03T17:27:48.521408Z","end":"2026-02-03T17:27:48.838536Z","steps":["trace[1842597873] 'read index received' (duration: 206.183056ms)","trace[1842597873] 'applied index is now lower than readState.Index' (duration: 110.944465ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:48.839035Z","caller":"traceutil/trace.go:171","msg":"trace[999678748] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"668.989059ms","start":"2026-02-03T17:27:48.170022Z","end":"2026-02-03T17:27:48.839011Z","steps":["trace[999678748] 'process raft request' (duration: 557.608695ms)","trace[999678748] 'compare' (duration: 101.8986ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:48.840314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.170007Z","time spent":"670.285119ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:48.84063Z","caller":"traceutil/trace.go:171","msg":"trace[2127523238] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"670.540095ms","start":"2026-02-03T17:27:48.170082Z","end":"2026-02-03T17:27:48.840622Z","steps":["trace[2127523238] 'process raft request' (duration: 668.378975ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.840666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.170079Z","time spent":"670.569055ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-03T17:27:48.841547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.140869ms","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-03T17:27:48.841581Z","caller":"traceutil/trace.go:171","msg":"trace[226606085] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:737; }","duration":"320.186261ms","start":"2026-02-03T17:27:48.521376Z","end":"2026-02-03T17:27:48.841562Z","steps":["trace[226606085] 'agreement among raft nodes before linearized reading' (duration: 320.036057ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.841599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.521362Z","time spent":"320.232821ms","remote":"127.0.0.1:59802","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-03T17:27:48.841823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.925556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-03T17:27:48.84184Z","caller":"traceutil/trace.go:171","msg":"trace[700210522] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:737; }","duration":"282.963748ms","start":"2026-02-03T17:27:48.558869Z","end":"2026-02-03T17:27:48.841833Z","steps":["trace[700210522] 'agreement among raft nodes before linearized reading' (duration: 282.865894ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:48.855094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.904672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:27:48.85515Z","caller":"traceutil/trace.go:171","msg":"trace[1801475968] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:737; }","duration":"161.995383ms","start":"2026-02-03T17:27:48.693142Z","end":"2026-02-03T17:27:48.855138Z","steps":["trace[1801475968] 'agreement among raft nodes before linearized reading' (duration: 149.049011ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:27:49.607063Z","caller":"traceutil/trace.go:171","msg":"trace[1016844535] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"749.013506ms","start":"2026-02-03T17:27:48.858009Z","end":"2026-02-03T17:27:49.607022Z","steps":["trace[1016844535] 'process raft request' (duration: 713.728465ms)","trace[1016844535] 'compare' (duration: 34.605164ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:27:49.607159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:27:48.857997Z","time spent":"749.111788ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:27:52.35117Z","caller":"traceutil/trace.go:171","msg":"trace[587478497] linearizableReadLoop","detail":"{readStateIndex:777; appliedIndex:776; }","duration":"137.947713ms","start":"2026-02-03T17:27:52.213205Z","end":"2026-02-03T17:27:52.351153Z","steps":["trace[587478497] 'read index received' (duration: 137.83108ms)","trace[587478497] 'applied index is now lower than readState.Index' (duration: 115.893µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:27:52.351241Z","caller":"traceutil/trace.go:171","msg":"trace[691988248] transaction","detail":"{read_only:false; response_revision:751; number_of_response:1; }","duration":"146.843506ms","start":"2026-02-03T17:27:52.204391Z","end":"2026-02-03T17:27:52.351234Z","steps":["trace[691988248] 'process raft request' (duration: 146.658792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:27:52.351326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.095917ms","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-03T17:27:52.351386Z","caller":"traceutil/trace.go:171","msg":"trace[1873779079] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:751; }","duration":"138.179749ms","start":"2026-02-03T17:27:52.213188Z","end":"2026-02-03T17:27:52.351367Z","steps":["trace[1873779079] 'agreement among raft nodes before linearized reading' (duration: 138.027486ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:03.26458Z","caller":"traceutil/trace.go:171","msg":"trace[1781302027] linearizableReadLoop","detail":"{readStateIndex:810; appliedIndex:809; }","duration":"358.972371ms","start":"2026-02-03T17:28:02.905563Z","end":"2026-02-03T17:28:03.264535Z","steps":["trace[1781302027] 'read index received' (duration: 358.728585ms)","trace[1781302027] 'applied index is now lower than readState.Index' (duration: 242.946µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:03.264917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.333959ms","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-03T17:28:03.265085Z","caller":"traceutil/trace.go:171","msg":"trace[433035955] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:781; }","duration":"359.401241ms","start":"2026-02-03T17:28:02.905542Z","end":"2026-02-03T17:28:03.264943Z","steps":["trace[433035955] 'agreement among raft nodes before linearized reading' (duration: 359.133844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:03.265124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:02.905521Z","time spent":"359.592466ms","remote":"127.0.0.1:59802","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-03T17:28:03.265241Z","caller":"traceutil/trace.go:171","msg":"trace[320994938] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"365.034385ms","start":"2026-02-03T17:28:02.900177Z","end":"2026-02-03T17:28:03.265211Z","steps":["trace[320994938] 'process raft request' (duration: 364.192115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:03.265375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:02.900159Z","time spent":"365.142698ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:05.4687Z","caller":"traceutil/trace.go:171","msg":"trace[1162629506] transaction","detail":"{read_only:false; response_revision:787; number_of_response:1; }","duration":"144.46842ms","start":"2026-02-03T17:28:05.324205Z","end":"2026-02-03T17:28:05.468673Z","steps":["trace[1162629506] 'process raft request' (duration: 128.848606ms)","trace[1162629506] 'compare' (duration: 15.504891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:07.051276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.915676ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-03T17:28:07.051358Z","caller":"traceutil/trace.go:171","msg":"trace[371577527] linearizableReadLoop","detail":"{readStateIndex:819; appliedIndex:818; }","duration":"515.950498ms","start":"2026-02-03T17:28:06.535394Z","end":"2026-02-03T17:28:07.051345Z","steps":["trace[371577527] 'read index received' (duration: 51.919789ms)","trace[371577527] 'applied index is now lower than readState.Index' (duration: 464.029889ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:07.051408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.02069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:07.051429Z","caller":"traceutil/trace.go:171","msg":"trace[649512963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:790; }","duration":"516.075211ms","start":"2026-02-03T17:28:06.535347Z","end":"2026-02-03T17:28:07.051422Z","steps":["trace[649512963] 'agreement among raft nodes before linearized reading' (duration: 516.02886ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:07.05145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:06.535331Z","time spent":"516.113423ms","remote":"127.0.0.1:59554","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-03T17:28:07.051623Z","caller":"traceutil/trace.go:171","msg":"trace[291186472] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"560.596915ms","start":"2026-02-03T17:28:06.491018Z","end":"2026-02-03T17:28:07.051615Z","steps":["trace[291186472] 'process raft request' (duration: 96.284529ms)","trace[291186472] 'compare' (duration: 463.836835ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:07.051663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:06.490996Z","time spent":"560.649185ms","remote":"127.0.0.1:59802","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-03T17:28:07.410744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.754421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-03T17:28:07.410792Z","caller":"traceutil/trace.go:171","msg":"trace[661243956] range","detail":"{range_begin:/registry/masterleases/199.19.213.207; range_end:; response_count:1; response_revision:790; }","duration":"220.848703ms","start":"2026-02-03T17:28:07.189932Z","end":"2026-02-03T17:28:07.410781Z","steps":["trace[661243956] 'range keys from in-memory index tree' (duration: 220.656049ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:07.720104Z","caller":"traceutil/trace.go:171","msg":"trace[1614048543] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"304.644963ms","start":"2026-02-03T17:28:07.415438Z","end":"2026-02-03T17:28:07.720083Z","steps":["trace[1614048543] 'process raft request' (duration: 304.541941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:07.720224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:07.415411Z","time spent":"304.751815ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:07.914769Z","caller":"traceutil/trace.go:171","msg":"trace[592076093] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:820; }","duration":"441.189474ms","start":"2026-02-03T17:28:07.47356Z","end":"2026-02-03T17:28:07.91475Z","steps":["trace[592076093] 'read index received' (duration: 247.294295ms)","trace[592076093] 'applied index is now lower than readState.Index' (duration: 193.894209ms)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:07.914864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:07.41565Z","time spent":"499.210709ms","remote":"127.0.0.1:59578","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T17:28:07.915394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"441.816869ms","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-03T17:28:07.915431Z","caller":"traceutil/trace.go:171","msg":"trace[49208711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"441.895731ms","start":"2026-02-03T17:28:07.473525Z","end":"2026-02-03T17:28:07.91542Z","steps":["trace[49208711] 'agreement among raft nodes before linearized reading' (duration: 441.755268ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:07.915462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:07.473511Z","time spent":"441.944362ms","remote":"127.0.0.1:59802","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-03T17:28:07.916139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.595163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:07.916183Z","caller":"traceutil/trace.go:171","msg":"trace[1514122891] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"380.184227ms","start":"2026-02-03T17:28:07.535986Z","end":"2026-02-03T17:28:07.916171Z","steps":["trace[1514122891] 'agreement among raft nodes before linearized reading' (duration: 379.608293ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:07.916316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:07.535967Z","time spent":"380.33748ms","remote":"127.0.0.1:59554","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-03T17:28:07.916591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.43939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:07.916675Z","caller":"traceutil/trace.go:171","msg":"trace[797965431] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:791; }","duration":"209.493722ms","start":"2026-02-03T17:28:07.707118Z","end":"2026-02-03T17:28:07.916612Z","steps":["trace[797965431] 'agreement among raft nodes before linearized reading' (duration: 209.438941ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:07.916859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.141884ms","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-03T17:28:07.916885Z","caller":"traceutil/trace.go:171","msg":"trace[23418643] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:791; }","duration":"245.200004ms","start":"2026-02-03T17:28:07.671677Z","end":"2026-02-03T17:28:07.916877Z","steps":["trace[23418643] 'agreement among raft nodes before linearized reading' (duration: 245.121263ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:11.225701Z","caller":"traceutil/trace.go:171","msg":"trace[1360293128] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"158.144076ms","start":"2026-02-03T17:28:11.067536Z","end":"2026-02-03T17:28:11.22568Z","steps":["trace[1360293128] 'process raft request' (duration: 157.943171ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:12.1607Z","caller":"traceutil/trace.go:171","msg":"trace[90082569] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"154.680003ms","start":"2026-02-03T17:28:12.006003Z","end":"2026-02-03T17:28:12.160683Z","steps":["trace[90082569] 'process raft request' (duration: 154.571651ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:12.170905Z","caller":"traceutil/trace.go:171","msg":"trace[1047067521] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"153.001253ms","start":"2026-02-03T17:28:12.017887Z","end":"2026-02-03T17:28:12.170889Z","steps":["trace[1047067521] 'process raft request' (duration: 152.90383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:21.75875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.846735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:21.758815Z","caller":"traceutil/trace.go:171","msg":"trace[1893884998] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:887; }","duration":"223.957227ms","start":"2026-02-03T17:28:21.534844Z","end":"2026-02-03T17:28:21.758802Z","steps":["trace[1893884998] 'range keys from in-memory index tree' (duration: 223.724501ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:24.808086Z","caller":"traceutil/trace.go:171","msg":"trace[445954919] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"252.181651ms","start":"2026-02-03T17:28:24.555884Z","end":"2026-02-03T17:28:24.808065Z","steps":["trace[445954919] 'process raft request' (duration: 252.033997ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:34.288212Z","caller":"traceutil/trace.go:171","msg":"trace[1604621031] transaction","detail":"{read_only:false; response_revision:921; number_of_response:1; }","duration":"260.005447ms","start":"2026-02-03T17:28:34.028183Z","end":"2026-02-03T17:28:34.288189Z","steps":["trace[1604621031] 'process raft request' (duration: 259.70605ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:35.245355Z","caller":"traceutil/trace.go:171","msg":"trace[444277336] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"362.943136ms","start":"2026-02-03T17:28:34.882363Z","end":"2026-02-03T17:28:35.245306Z","steps":["trace[444277336] 'process raft request' (duration: 362.755222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:35.245655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:34.882354Z","time spent":"363.09762ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:37.51791Z","caller":"traceutil/trace.go:171","msg":"trace[1174918490] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"268.282525ms","start":"2026-02-03T17:28:37.249614Z","end":"2026-02-03T17:28:37.517897Z","steps":["trace[1174918490] 'read index received' (duration: 268.159432ms)","trace[1174918490] 'applied index is now lower than readState.Index' (duration: 122.403µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:37.517965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:37.1937Z","time spent":"324.263202ms","remote":"127.0.0.1:59578","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-03T17:28:37.518135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.51064ms","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-03T17:28:37.518156Z","caller":"traceutil/trace.go:171","msg":"trace[689310446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:928; }","duration":"268.552741ms","start":"2026-02-03T17:28:37.249598Z","end":"2026-02-03T17:28:37.518151Z","steps":["trace[689310446] 'agreement among raft nodes before linearized reading' (duration: 268.475659ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:38.513295Z","caller":"traceutil/trace.go:171","msg":"trace[2042998448] linearizableReadLoop","detail":"{readStateIndex:971; appliedIndex:970; }","duration":"199.437213ms","start":"2026-02-03T17:28:38.313833Z","end":"2026-02-03T17:28:38.513271Z","steps":["trace[2042998448] 'read index received' (duration: 199.202197ms)","trace[2042998448] 'applied index is now lower than readState.Index' (duration: 233.986µs)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:38.513417Z","caller":"traceutil/trace.go:171","msg":"trace[1830286056] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"210.745582ms","start":"2026-02-03T17:28:38.30266Z","end":"2026-02-03T17:28:38.513406Z","steps":["trace[1830286056] 'process raft request' (duration: 210.456005ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:38.513658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.66158ms","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-03T17:28:38.513727Z","caller":"traceutil/trace.go:171","msg":"trace[2534631] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:932; }","duration":"192.745772ms","start":"2026-02-03T17:28:38.320966Z","end":"2026-02-03T17:28:38.513712Z","steps":["trace[2534631] 'agreement among raft nodes before linearized reading' (duration: 192.577298ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:38.513898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.053797ms","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-03T17:28:38.513939Z","caller":"traceutil/trace.go:171","msg":"trace[147501881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:932; }","duration":"200.127179ms","start":"2026-02-03T17:28:38.313798Z","end":"2026-02-03T17:28:38.513925Z","steps":["trace[147501881] 'agreement among raft nodes before linearized reading' (duration: 199.992946ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:39.742549Z","caller":"traceutil/trace.go:171","msg":"trace[732280662] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"183.268606ms","start":"2026-02-03T17:28:39.559263Z","end":"2026-02-03T17:28:39.742532Z","steps":["trace[732280662] 'process raft request' (duration: 183.139903ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:40.066714Z","caller":"traceutil/trace.go:171","msg":"trace[493428679] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"507.327363ms","start":"2026-02-03T17:28:39.559371Z","end":"2026-02-03T17:28:40.066698Z","steps":["trace[493428679] 'process raft request' (duration: 507.256031ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:40.066797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:39.559367Z","time spent":"507.386624ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:41.877692Z","caller":"traceutil/trace.go:171","msg":"trace[1303926601] linearizableReadLoop","detail":"{readStateIndex:980; appliedIndex:979; }","duration":"131.637613ms","start":"2026-02-03T17:28:41.746039Z","end":"2026-02-03T17:28:41.877676Z","steps":["trace[1303926601] 'read index received' (duration: 131.50434ms)","trace[1303926601] 'applied index is now lower than readState.Index' (duration: 132.733µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:28:41.877837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.781816ms","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-03T17:28:41.877853Z","caller":"traceutil/trace.go:171","msg":"trace[1922296900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"131.850068ms","start":"2026-02-03T17:28:41.745998Z","end":"2026-02-03T17:28:41.877848Z","steps":["trace[1922296900] 'agreement among raft nodes before linearized reading' (duration: 131.736905ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:41.877939Z","caller":"traceutil/trace.go:171","msg":"trace[449620522] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"875.085484ms","start":"2026-02-03T17:28:41.002848Z","end":"2026-02-03T17:28:41.877933Z","steps":["trace[449620522] 'process raft request' (duration: 874.737626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:41.877984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:41.00283Z","time spent":"875.118615ms","remote":"127.0.0.1:59802","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-03T17:28:42.186772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.391253ms","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-03T17:28:42.186825Z","caller":"traceutil/trace.go:171","msg":"trace[139169896] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:942; }","duration":"117.498845ms","start":"2026-02-03T17:28:42.069314Z","end":"2026-02-03T17:28:42.186813Z","steps":["trace[139169896] 'range keys from in-memory index tree' (duration: 117.120237ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:42.41712Z","caller":"traceutil/trace.go:171","msg":"trace[1227968955] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"269.187007ms","start":"2026-02-03T17:28:42.147855Z","end":"2026-02-03T17:28:42.417042Z","steps":["trace[1227968955] 'process raft request' (duration: 269.072014ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:42.447934Z","caller":"traceutil/trace.go:171","msg":"trace[561525574] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"257.930018ms","start":"2026-02-03T17:28:42.189985Z","end":"2026-02-03T17:28:42.447915Z","steps":["trace[561525574] 'process raft request' (duration: 257.816245ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:43.198089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.165022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-03T17:28:43.198151Z","caller":"traceutil/trace.go:171","msg":"trace[1904535372] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:944; }","duration":"665.268444ms","start":"2026-02-03T17:28:42.53287Z","end":"2026-02-03T17:28:43.198138Z","steps":["trace[1904535372] 'range keys from in-memory index tree' (duration: 665.09712ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:43.198182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:42.532849Z","time spent":"665.326455ms","remote":"127.0.0.1:59554","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-03T17:28:43.198345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.504394ms","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-03T17:28:43.198359Z","caller":"traceutil/trace.go:171","msg":"trace[1426116643] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:944; }","duration":"643.540795ms","start":"2026-02-03T17:28:42.554814Z","end":"2026-02-03T17:28:43.198355Z","steps":["trace[1426116643] 'range keys from in-memory index tree' (duration: 643.418162ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:43.198374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:42.554804Z","time spent":"643.565826ms","remote":"127.0.0.1:59802","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-03T17:28:43.198466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"606.836069ms","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-03T17:28:43.198482Z","caller":"traceutil/trace.go:171","msg":"trace[789311657] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:944; }","duration":"606.87297ms","start":"2026-02-03T17:28:42.591603Z","end":"2026-02-03T17:28:43.198476Z","steps":["trace[789311657] 'range keys from in-memory index tree' (duration: 606.774427ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:43.198493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:42.591593Z","time spent":"606.89722ms","remote":"127.0.0.1:59802","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-03T17:28:43.198604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.548022ms","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-03T17:28:43.198616Z","caller":"traceutil/trace.go:171","msg":"trace[553649881] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:944; }","duration":"557.588722ms","start":"2026-02-03T17:28:42.641024Z","end":"2026-02-03T17:28:43.198612Z","steps":["trace[553649881] 'range keys from in-memory index tree' (duration: 557.424789ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:43.198629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:42.64101Z","time spent":"557.616084ms","remote":"127.0.0.1:59802","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-03T17:28:45.540571Z","caller":"traceutil/trace.go:171","msg":"trace[816326921] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"111.479612ms","start":"2026-02-03T17:28:45.42908Z","end":"2026-02-03T17:28:45.540559Z","steps":["trace[816326921] 'process raft request' (duration: 58.515838ms)","trace[816326921] 'compare' (duration: 52.640107ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:28:45.540732Z","caller":"traceutil/trace.go:171","msg":"trace[2040924919] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"106.703967ms","start":"2026-02-03T17:28:45.434022Z","end":"2026-02-03T17:28:45.540726Z","steps":["trace[2040924919] 'process raft request' (duration: 106.297538ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:48.790207Z","caller":"traceutil/trace.go:171","msg":"trace[1782897705] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"307.319707ms","start":"2026-02-03T17:28:48.480921Z","end":"2026-02-03T17:28:48.788241Z","steps":["trace[1782897705] 'process raft request' (duration: 306.910257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:28:48.79057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:28:48.480903Z","time spent":"309.51869ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:28:53.511326Z","caller":"traceutil/trace.go:171","msg":"trace[2007080487] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"109.33021ms","start":"2026-02-03T17:28:53.401975Z","end":"2026-02-03T17:28:53.511306Z","steps":["trace[2007080487] 'process raft request' (duration: 109.155266ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:54.792626Z","caller":"traceutil/trace.go:171","msg":"trace[236870544] transaction","detail":"{read_only:false; response_revision:1012; number_of_response:1; }","duration":"252.182982ms","start":"2026-02-03T17:28:54.540418Z","end":"2026-02-03T17:28:54.792601Z","steps":["trace[236870544] 'process raft request' (duration: 252.013338ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:28:54.96676Z","caller":"traceutil/trace.go:171","msg":"trace[2036391199] transaction","detail":"{read_only:false; response_revision:1013; number_of_response:1; }","duration":"156.235561ms","start":"2026-02-03T17:28:54.810498Z","end":"2026-02-03T17:28:54.966734Z","steps":["trace[2036391199] 'process raft request' (duration: 147.82932ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.912757Z","caller":"traceutil/trace.go:171","msg":"trace[336489832] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"255.490752ms","start":"2026-02-03T17:29:13.657245Z","end":"2026-02-03T17:29:13.912735Z","steps":["trace[336489832] 'read index received' (duration: 255.300888ms)","trace[336489832] 'applied index is now lower than readState.Index' (duration: 188.834µs)"],"step_count":2} {"level":"warn","ts":"2026-02-03T17:29:13.917414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.147544ms","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-03T17:29:13.917461Z","caller":"traceutil/trace.go:171","msg":"trace[1609261856] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1109; }","duration":"260.249306ms","start":"2026-02-03T17:29:13.657198Z","end":"2026-02-03T17:29:13.917447Z","steps":["trace[1609261856] 'agreement among raft nodes before linearized reading' (duration: 260.00215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:13.917961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.677506ms","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-03T17:29:13.918005Z","caller":"traceutil/trace.go:171","msg":"trace[1229077221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1109; }","duration":"260.723617ms","start":"2026-02-03T17:29:13.657272Z","end":"2026-02-03T17:29:13.917995Z","steps":["trace[1229077221] 'agreement among raft nodes before linearized reading' (duration: 260.618755ms)"],"step_count":1} {"level":"info","ts":"2026-02-03T17:29:13.913126Z","caller":"traceutil/trace.go:171","msg":"trace[15209036] transaction","detail":"{read_only:false; response_revision:1109; number_of_response:1; }","duration":"309.402192ms","start":"2026-02-03T17:29:13.60371Z","end":"2026-02-03T17:29:13.913113Z","steps":["trace[15209036] 'process raft request' (duration: 308.89825ms)"],"step_count":1} {"level":"warn","ts":"2026-02-03T17:29:13.920231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-03T17:29:13.603695Z","time spent":"315.020456ms","remote":"127.0.0.1:59802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-03T17:29:14.127679Z","caller":"traceutil/trace.go:171","msg":"trace[1533549519] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"204.682426ms","start":"2026-02-03T17:29:13.922973Z","end":"2026-02-03T17:29:14.127656Z","steps":["trace[1533549519] 'process raft request' (duration: 109.871428ms)","trace[1533549519] 'compare' (duration: 93.456435ms)"],"step_count":2} {"level":"info","ts":"2026-02-03T17:29:14.127997Z","caller":"traceutil/trace.go:171","msg":"trace[1713834416] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"202.68431ms","start":"2026-02-03T17:29:13.925182Z","end":"2026-02-03T17:29:14.127866Z","steps":["trace[1713834416] 'process raft request' (duration: 201.386939ms)"],"step_count":1}