{"level":"warn","ts":"2026-02-02T23:44:48.475591Z","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-02T23:44:48.475676Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62: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-02T23:44:48.475844Z","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-02T23:44:48.47586Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-02T23:44:48.475923Z","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-02T23:44:48.476751Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-02T23:44:48.476904Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62: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-02T23:44:48.484327Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.178509ms"} {"level":"info","ts":"2026-02-02T23:44:48.489855Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-02T23:44:48.489969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-02T23:44:48.490092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-02T23:44:48.490119Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-02T23:44:48.490204Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-02T23:44:48.490312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-02T23:44:48.498405Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-02T23:44:48.499624Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-02T23:44:48.500866Z","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-02T23:44:48.502568Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-02T23:44:48.508482Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-02T23:44:48.511522Z","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-02T23:44:48.511561Z","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-02T23:44:48.511568Z","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-02T23:44:48.511845Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-02T23:44:48.511999Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-02T23:44:48.541422Z","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-02T23:44:48.541599Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-02T23:44:48.541622Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-02T23:44:48.541718Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-02T23:44:48.541727Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-02T23:44:48.69083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-02T23:44:48.690866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-02T23:44:48.690882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-02T23:44:48.690892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-02T23:44:48.690898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-02T23:44:48.690905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-02T23:44:48.690912Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-02T23:44:48.693074Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-02T23:44:48.693227Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T23:44:48.693313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T23:44:48.69366Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-02T23:44:48.693976Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-02T23:44:48.694063Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-02T23:44:48.695486Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-02T23:44:48.695629Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T23:44:48.695798Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T23:44:48.695852Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-02T23:44:48.697324Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-02T23:45:09.604167Z","caller":"traceutil/trace.go:171","msg":"trace[712796719] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"362.621488ms","start":"2026-02-02T23:45:09.241524Z","end":"2026-02-02T23:45:09.604145Z","steps":["trace[712796719] 'process raft request' (duration: 362.501637ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:09.604757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:09.241513Z","time spent":"362.728779ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:09.739218Z","caller":"traceutil/trace.go:171","msg":"trace[284212957] transaction","detail":"{read_only:false; response_revision:415; number_of_response:1; }","duration":"497.498354ms","start":"2026-02-02T23:45:09.241703Z","end":"2026-02-02T23:45:09.739202Z","steps":["trace[284212957] 'process raft request' (duration: 419.631203ms)","trace[284212957] 'compare' (duration: 77.80676ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T23:45:09.739362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:09.241695Z","time spent":"497.561374ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:14.784955Z","caller":"traceutil/trace.go:171","msg":"trace[488806641] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"139.541939ms","start":"2026-02-02T23:45:14.645396Z","end":"2026-02-02T23:45:14.784938Z","steps":["trace[488806641] 'process raft request' (duration: 139.440278ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:45:19.084693Z","caller":"traceutil/trace.go:171","msg":"trace[577502551] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"121.437323ms","start":"2026-02-02T23:45:18.963234Z","end":"2026-02-02T23:45:19.084672Z","steps":["trace[577502551] 'process raft request' (duration: 121.300532ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:19.735251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.560186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:19.735316Z","caller":"traceutil/trace.go:171","msg":"trace[1863063659] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:470; }","duration":"310.655126ms","start":"2026-02-02T23:45:19.42465Z","end":"2026-02-02T23:45:19.735305Z","steps":["trace[1863063659] 'range keys from in-memory index tree' (duration: 310.487256ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:19.735334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:19.424623Z","time spent":"310.706167ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T23:45:19.920588Z","caller":"traceutil/trace.go:171","msg":"trace[863084347] linearizableReadLoop","detail":"{readStateIndex:484; appliedIndex:483; }","duration":"144.371028ms","start":"2026-02-02T23:45:19.776197Z","end":"2026-02-02T23:45:19.920568Z","steps":["trace[863084347] 'read index received' (duration: 144.222867ms)","trace[863084347] 'applied index is now lower than readState.Index' (duration: 147.451µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T23:45:19.920829Z","caller":"traceutil/trace.go:171","msg":"trace[1180994514] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"171.345897ms","start":"2026-02-02T23:45:19.749472Z","end":"2026-02-02T23:45:19.920818Z","steps":["trace[1180994514] 'process raft request' (duration: 170.996775ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:19.921057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.845362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4018"} {"level":"info","ts":"2026-02-02T23:45:19.921092Z","caller":"traceutil/trace.go:171","msg":"trace[1949427917] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:471; }","duration":"144.915412ms","start":"2026-02-02T23:45:19.776166Z","end":"2026-02-02T23:45:19.921081Z","steps":["trace[1949427917] 'agreement among raft nodes before linearized reading' (duration: 144.845812ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:45:21.217002Z","caller":"traceutil/trace.go:171","msg":"trace[407778842] linearizableReadLoop","detail":"{readStateIndex:511; appliedIndex:510; }","duration":"127.649503ms","start":"2026-02-02T23:45:21.089337Z","end":"2026-02-02T23:45:21.216986Z","steps":["trace[407778842] 'read index received' (duration: 73.934544ms)","trace[407778842] 'applied index is now lower than readState.Index' (duration: 53.714389ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T23:45:21.217163Z","caller":"traceutil/trace.go:171","msg":"trace[1641523443] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"172.402304ms","start":"2026-02-02T23:45:21.044755Z","end":"2026-02-02T23:45:21.217157Z","steps":["trace[1641523443] 'process raft request' (duration: 118.562434ms)","trace[1641523443] 'compare' (duration: 53.304066ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T23:45:21.217283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.936095ms","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-02T23:45:21.2173Z","caller":"traceutil/trace.go:171","msg":"trace[1676719897] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:497; }","duration":"127.962835ms","start":"2026-02-02T23:45:21.089333Z","end":"2026-02-02T23:45:21.217295Z","steps":["trace[1676719897] 'agreement among raft nodes before linearized reading' (duration: 127.918555ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:45:21.907221Z","caller":"traceutil/trace.go:171","msg":"trace[721296006] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"344.524287ms","start":"2026-02-02T23:45:21.562682Z","end":"2026-02-02T23:45:21.907206Z","steps":["trace[721296006] 'process raft request' (duration: 341.170492ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:21.907313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:21.562663Z","time spent":"344.598778ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:21.912571Z","caller":"traceutil/trace.go:171","msg":"trace[811693816] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"112.163615ms","start":"2026-02-02T23:45:21.800397Z","end":"2026-02-02T23:45:21.912561Z","steps":["trace[811693816] 'process raft request' (duration: 112.146385ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:45:21.912895Z","caller":"traceutil/trace.go:171","msg":"trace[1594964979] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"314.312496ms","start":"2026-02-02T23:45:21.598565Z","end":"2026-02-02T23:45:21.912878Z","steps":["trace[1594964979] 'process raft request' (duration: 313.939664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:21.912952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:21.598546Z","time spent":"314.369698ms","remote":"127.0.0.1:40246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-02T23:45:22.381904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.455798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-02T23:45:22.381928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.878886ms","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-02T23:45:22.381955Z","caller":"traceutil/trace.go:171","msg":"trace[958591189] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:517; }","duration":"364.535968ms","start":"2026-02-02T23:45:22.017404Z","end":"2026-02-02T23:45:22.38194Z","steps":["trace[958591189] 'range keys from in-memory index tree' (duration: 364.407188ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:45:22.381972Z","caller":"traceutil/trace.go:171","msg":"trace[1115812327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:517; }","duration":"111.954857ms","start":"2026-02-02T23:45:22.270006Z","end":"2026-02-02T23:45:22.381961Z","steps":["trace[1115812327] 'range keys from in-memory index tree' (duration: 111.779115ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:22.381991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:22.01739Z","time spent":"364.594669ms","remote":"127.0.0.1:40246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-02T23:45:22.382144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.668957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:22.382159Z","caller":"traceutil/trace.go:171","msg":"trace[1507130778] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:517; }","duration":"184.704798ms","start":"2026-02-02T23:45:22.197449Z","end":"2026-02-02T23:45:22.382154Z","steps":["trace[1507130778] 'range keys from in-memory index tree' (duration: 184.636807ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:28.583633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.167683ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:28.583717Z","caller":"traceutil/trace.go:171","msg":"trace[1308895645] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:564; }","duration":"181.266213ms","start":"2026-02-02T23:45:28.40244Z","end":"2026-02-02T23:45:28.583706Z","steps":["trace[1308895645] 'range keys from in-memory index tree' (duration: 181.153813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.786242Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674491340975847,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-02T23:45:29.867336Z","caller":"traceutil/trace.go:171","msg":"trace[1221623861] linearizableReadLoop","detail":"{readStateIndex:586; appliedIndex:585; }","duration":"581.605021ms","start":"2026-02-02T23:45:29.285708Z","end":"2026-02-02T23:45:29.867313Z","steps":["trace[1221623861] 'read index received' (duration: 581.40194ms)","trace[1221623861] 'applied index is now lower than readState.Index' (duration: 202.381µs)"],"step_count":2} {"level":"info","ts":"2026-02-02T23:45:29.867632Z","caller":"traceutil/trace.go:171","msg":"trace[1313923617] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"693.9906ms","start":"2026-02-02T23:45:29.17363Z","end":"2026-02-02T23:45:29.867621Z","steps":["trace[1313923617] 'process raft request' (duration: 693.562207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.867732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.173613Z","time spent":"694.059551ms","remote":"127.0.0.1:40390","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T23:45:29.867967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.254336ms","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-02T23:45:29.867998Z","caller":"traceutil/trace.go:171","msg":"trace[2101685893] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:570; }","duration":"582.317686ms","start":"2026-02-02T23:45:29.28567Z","end":"2026-02-02T23:45:29.867988Z","steps":["trace[2101685893] 'agreement among raft nodes before linearized reading' (duration: 582.214455ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.86804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.285652Z","time spent":"582.381716ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-02T23:45:29.868194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.385012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.86822Z","caller":"traceutil/trace.go:171","msg":"trace[1864945770] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"444.443822ms","start":"2026-02-02T23:45:29.423768Z","end":"2026-02-02T23:45:29.868212Z","steps":["trace[1864945770] 'agreement among raft nodes before linearized reading' (duration: 444.404312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.868245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.42375Z","time spent":"444.488373ms","remote":"127.0.0.1:40200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T23:45:29.868593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.130971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.86863Z","caller":"traceutil/trace.go:171","msg":"trace[807510198] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"390.168121ms","start":"2026-02-02T23:45:29.478451Z","end":"2026-02-02T23:45:29.868619Z","steps":["trace[807510198] 'agreement among raft nodes before linearized reading' (duration: 390.112861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.868658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.47843Z","time spent":"390.220612ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-02T23:45:29.868796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.358343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.868823Z","caller":"traceutil/trace.go:171","msg":"trace[1542144129] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"390.395083ms","start":"2026-02-02T23:45:29.47842Z","end":"2026-02-02T23:45:29.868815Z","steps":["trace[1542144129] 'agreement among raft nodes before linearized reading' (duration: 390.350713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.868849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.478418Z","time spent":"390.424453ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-02T23:45:29.868953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.535994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.868979Z","caller":"traceutil/trace.go:171","msg":"trace[788366999] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:570; }","duration":"390.569024ms","start":"2026-02-02T23:45:29.478401Z","end":"2026-02-02T23:45:29.86897Z","steps":["trace[788366999] 'agreement among raft nodes before linearized reading' (duration: 390.532114ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.869007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.478396Z","time spent":"390.601465ms","remote":"127.0.0.1:60490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T23:45:29.871463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.278455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.871506Z","caller":"traceutil/trace.go:171","msg":"trace[1975672418] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"400.331575ms","start":"2026-02-02T23:45:29.471163Z","end":"2026-02-02T23:45:29.871494Z","steps":["trace[1975672418] 'agreement among raft nodes before linearized reading' (duration: 400.259695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.871538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.471158Z","time spent":"400.371176ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T23:45:29.87168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.516997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.87171Z","caller":"traceutil/trace.go:171","msg":"trace[1352273464] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"400.547477ms","start":"2026-02-02T23:45:29.471155Z","end":"2026-02-02T23:45:29.871702Z","steps":["trace[1352273464] 'agreement among raft nodes before linearized reading' (duration: 400.505337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.871736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.471131Z","time spent":"400.598267ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-02T23:45:29.871845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.714468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.871869Z","caller":"traceutil/trace.go:171","msg":"trace[1505377736] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:570; }","duration":"400.756428ms","start":"2026-02-02T23:45:29.471106Z","end":"2026-02-02T23:45:29.871862Z","steps":["trace[1505377736] 'agreement among raft nodes before linearized reading' (duration: 400.718478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.871895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.471096Z","time spent":"400.792258ms","remote":"127.0.0.1:60488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-02T23:45:29.871998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.296774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.872145Z","caller":"traceutil/trace.go:171","msg":"trace[1802261043] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"411.463095ms","start":"2026-02-02T23:45:29.46067Z","end":"2026-02-02T23:45:29.872133Z","steps":["trace[1802261043] 'agreement among raft nodes before linearized reading' (duration: 411.308734ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.872183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.460654Z","time spent":"411.516395ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-02T23:45:29.87255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.883437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.872594Z","caller":"traceutil/trace.go:171","msg":"trace[1637051563] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"415.941547ms","start":"2026-02-02T23:45:29.456641Z","end":"2026-02-02T23:45:29.872583Z","steps":["trace[1637051563] 'agreement among raft nodes before linearized reading' (duration: 415.872837ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.872643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.456638Z","time spent":"415.996798ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-02T23:45:29.872941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.437511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.873019Z","caller":"traceutil/trace.go:171","msg":"trace[59550035] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:570; }","duration":"416.534151ms","start":"2026-02-02T23:45:29.456472Z","end":"2026-02-02T23:45:29.873006Z","steps":["trace[59550035] 'agreement among raft nodes before linearized reading' (duration: 416.38666ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.873073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.456467Z","time spent":"416.598642ms","remote":"127.0.0.1:60484","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T23:45:29.873196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.566169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.873226Z","caller":"traceutil/trace.go:171","msg":"trace[510497525] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"424.61103ms","start":"2026-02-02T23:45:29.448609Z","end":"2026-02-02T23:45:29.87322Z","steps":["trace[510497525] 'agreement among raft nodes before linearized reading' (duration: 424.555859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.873252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.4486Z","time spent":"424.64681ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:10000 "} {"level":"warn","ts":"2026-02-02T23:45:29.873399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.797571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.873442Z","caller":"traceutil/trace.go:171","msg":"trace[1115941068] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"424.849061ms","start":"2026-02-02T23:45:29.448588Z","end":"2026-02-02T23:45:29.873437Z","steps":["trace[1115941068] 'agreement among raft nodes before linearized reading' (duration: 424.794781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.87347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.448586Z","time spent":"424.878402ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true "} {"level":"warn","ts":"2026-02-02T23:45:29.873533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.947422ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:29.873568Z","caller":"traceutil/trace.go:171","msg":"trace[482285776] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:570; }","duration":"425.000122ms","start":"2026-02-02T23:45:29.448561Z","end":"2026-02-02T23:45:29.873561Z","steps":["trace[482285776] 'agreement among raft nodes before linearized reading' (duration: 424.948932ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:29.873594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:29.44855Z","time spent":"425.038222ms","remote":"127.0.0.1:60474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" limit:500 "} {"level":"warn","ts":"2026-02-02T23:45:30.069761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.883601ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T23:45:30.069851Z","caller":"traceutil/trace.go:171","msg":"trace[1296272600] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"186.382751ms","start":"2026-02-02T23:45:29.883456Z","end":"2026-02-02T23:45:30.069839Z","steps":["trace[1296272600] 'process raft request' (duration: 73.257508ms)","trace[1296272600] 'compare' (duration: 112.73629ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T23:45:31.516713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.259323815s","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-02T23:45:31.516761Z","caller":"traceutil/trace.go:171","msg":"trace[461234985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.259402436s","start":"2026-02-02T23:45:30.257347Z","end":"2026-02-02T23:45:31.516749Z","steps":["trace[461234985] 'range keys from in-memory index tree' (duration: 1.259196155s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:31.516781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:30.257333Z","time spent":"1.259444206s","remote":"127.0.0.1:40462","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-02T23:45:31.516886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25846379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-02T23:45:31.516898Z","caller":"traceutil/trace.go:171","msg":"trace[728930855] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:571; }","duration":"1.25851174s","start":"2026-02-02T23:45:30.258383Z","end":"2026-02-02T23:45:31.516895Z","steps":["trace[728930855] 'range keys from in-memory index tree' (duration: 1.258360219s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:31.51691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:30.258342Z","time spent":"1.25856508s","remote":"127.0.0.1:40462","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-02T23:45:31.517004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.092438571s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:31.517017Z","caller":"traceutil/trace.go:171","msg":"trace[1455338635] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"1.092484111s","start":"2026-02-02T23:45:30.424528Z","end":"2026-02-02T23:45:31.517012Z","steps":["trace[1455338635] 'range keys from in-memory index tree' (duration: 1.09240136s)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:31.517028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:30.424515Z","time spent":"1.092509561s","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-02T23:45:31.51725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.489545ms","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-02T23:45:31.517266Z","caller":"traceutil/trace.go:171","msg":"trace[1080309078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:571; }","duration":"777.558485ms","start":"2026-02-02T23:45:30.739702Z","end":"2026-02-02T23:45:31.517261Z","steps":["trace[1080309078] 'range keys from in-memory index tree' (duration: 777.386604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:31.517279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:30.739685Z","time spent":"777.591456ms","remote":"127.0.0.1:40462","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-02T23:45:31.517381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.676838ms","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-02T23:45:31.517393Z","caller":"traceutil/trace.go:171","msg":"trace[1266019807] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:571; }","duration":"639.724608ms","start":"2026-02-02T23:45:30.877665Z","end":"2026-02-02T23:45:31.51739Z","steps":["trace[1266019807] 'range keys from in-memory index tree' (duration: 639.614078ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:31.517404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:30.877652Z","time spent":"639.749388ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-02T23:45:32.027544Z","caller":"traceutil/trace.go:171","msg":"trace[1638589002] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"505.587408ms","start":"2026-02-02T23:45:31.521942Z","end":"2026-02-02T23:45:32.027529Z","steps":["trace[1638589002] 'process raft request' (duration: 505.502567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:32.027874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:31.521933Z","time spent":"505.652778ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:32.028075Z","caller":"traceutil/trace.go:171","msg":"trace[2091805901] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:588; }","duration":"492.899287ms","start":"2026-02-02T23:45:31.535169Z","end":"2026-02-02T23:45:32.028068Z","steps":["trace[2091805901] 'read index received' (duration: 492.825167ms)","trace[2091805901] 'applied index is now lower than readState.Index' (duration: 73.71µs)"],"step_count":2} {"level":"warn","ts":"2026-02-02T23:45:32.028119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"492.948998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:32.028132Z","caller":"traceutil/trace.go:171","msg":"trace[2001126666] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:575; }","duration":"492.995838ms","start":"2026-02-02T23:45:31.535132Z","end":"2026-02-02T23:45:32.028128Z","steps":["trace[2001126666] 'agreement among raft nodes before linearized reading' (duration: 492.957588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:32.028147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:31.535123Z","time spent":"493.020728ms","remote":"127.0.0.1:40214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-02T23:45:32.028245Z","caller":"traceutil/trace.go:171","msg":"trace[24405640] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"504.796102ms","start":"2026-02-02T23:45:31.523444Z","end":"2026-02-02T23:45:32.02824Z","steps":["trace[24405640] 'process raft request' (duration: 504.56297ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:32.028284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:31.523437Z","time spent":"504.818542ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:32.028321Z","caller":"traceutil/trace.go:171","msg":"trace[758356735] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"502.511786ms","start":"2026-02-02T23:45:31.525806Z","end":"2026-02-02T23:45:32.028317Z","steps":["trace[758356735] 'process raft request' (duration: 502.232334ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:32.028342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:31.525798Z","time spent":"502.531866ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-02T23:45:32.028374Z","caller":"traceutil/trace.go:171","msg":"trace[1560620638] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"502.483736ms","start":"2026-02-02T23:45:31.525886Z","end":"2026-02-02T23:45:32.02837Z","steps":["trace[1560620638] 'process raft request' (duration: 502.167673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:45:32.028407Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-02T23:45:31.525882Z","time spent":"502.514576ms","remote":"127.0.0.1:40462","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-02T23:45:36.5985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.729682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:45:36.598553Z","caller":"traceutil/trace.go:171","msg":"trace[487168006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:609; }","duration":"173.838063ms","start":"2026-02-02T23:45:36.424702Z","end":"2026-02-02T23:45:36.59854Z","steps":["trace[487168006] 'range keys from in-memory index tree' (duration: 173.658092ms)"],"step_count":1} {"level":"warn","ts":"2026-02-02T23:46:56.80696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.676559ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-02T23:46:56.807766Z","caller":"traceutil/trace.go:171","msg":"trace[1408160305] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"140.327951ms","start":"2026-02-02T23:46:56.667425Z","end":"2026-02-02T23:46:56.807753Z","steps":["trace[1408160305] 'process raft request' (duration: 140.29212ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:46:56.808064Z","caller":"traceutil/trace.go:171","msg":"trace[745331230] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"206.835091ms","start":"2026-02-02T23:46:56.60122Z","end":"2026-02-02T23:46:56.808055Z","steps":["trace[745331230] 'process raft request' (duration: 15.986576ms)","trace[745331230] 'compare' (duration: 189.459754ms)"],"step_count":2} {"level":"info","ts":"2026-02-02T23:46:56.808764Z","caller":"traceutil/trace.go:171","msg":"trace[595816793] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:992; }","duration":"207.147227ms","start":"2026-02-02T23:46:56.601606Z","end":"2026-02-02T23:46:56.808753Z","steps":["trace[595816793] 'read index received' (duration: 15.607377ms)","trace[595816793] 'applied index is now lower than readState.Index' (duration: 191.53903ms)"],"step_count":2} {"level":"warn","ts":"2026-02-02T23:46:56.808958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.345022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-02T23:46:56.809044Z","caller":"traceutil/trace.go:171","msg":"trace[856724788] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:962; }","duration":"207.447924ms","start":"2026-02-02T23:46:56.601587Z","end":"2026-02-02T23:46:56.809035Z","steps":["trace[856724788] 'agreement among raft nodes before linearized reading' (duration: 207.342851ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:46:56.809196Z","caller":"traceutil/trace.go:171","msg":"trace[578003872] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"206.627576ms","start":"2026-02-02T23:46:56.602562Z","end":"2026-02-02T23:46:56.809189Z","steps":["trace[578003872] 'process raft request' (duration: 205.049531ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:46:56.809435Z","caller":"traceutil/trace.go:171","msg":"trace[183171947] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"204.695193ms","start":"2026-02-02T23:46:56.604731Z","end":"2026-02-02T23:46:56.809427Z","steps":["trace[183171947] 'process raft request' (duration: 202.920234ms)"],"step_count":1} {"level":"info","ts":"2026-02-02T23:46:56.809712Z","caller":"traceutil/trace.go:171","msg":"trace[1581777575] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"158.117106ms","start":"2026-02-02T23:46:56.651588Z","end":"2026-02-02T23:46:56.809705Z","steps":["trace[1581777575] 'process raft request' (duration: 156.088201ms)"],"step_count":1}