{"level":"warn","ts":"2026-03-09T01:36:59.313842Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:36:59.313968Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.74: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.74:2380","--initial-cluster=instance=https://162.253.55.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.74: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-03-09T01:36:59.314059Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-09T01:36:59.314075Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-09T01:36:59.314092Z","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-03-09T01:36:59.314791Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"]} {"level":"info","ts":"2026-03-09T01:36:59.314885Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.74: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-03-09T01:36:59.318638Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.487618ms"} {"level":"info","ts":"2026-03-09T01:36:59.325057Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"30aa8136fb61a722","cluster-id":"e7ea3889c4872560"} {"level":"info","ts":"2026-03-09T01:36:59.325228Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=()"} {"level":"info","ts":"2026-03-09T01:36:59.325322Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 0"} {"level":"info","ts":"2026-03-09T01:36:59.325343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 30aa8136fb61a722 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-09T01:36:59.325354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became follower at term 1"} {"level":"info","ts":"2026-03-09T01:36:59.325413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"warn","ts":"2026-03-09T01:36:59.331114Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-09T01:36:59.332371Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-09T01:36:59.333292Z","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-03-09T01:36:59.335556Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"30aa8136fb61a722","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-09T01:36:59.337784Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"30aa8136fb61a722","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-09T01:36:59.338665Z","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-03-09T01:36:59.338841Z","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-03-09T01:36:59.338867Z","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-03-09T01:36:59.34429Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 switched to configuration voters=(3506757333007050530)"} {"level":"info","ts":"2026-03-09T01:36:59.344399Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","added-peer-id":"30aa8136fb61a722","added-peer-peer-urls":["https://162.253.55.74:2380"]} {"level":"info","ts":"2026-03-09T01:36:59.345064Z","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-03-09T01:36:59.345369Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"30aa8136fb61a722","initial-advertise-peer-urls":["https://162.253.55.74:2380"],"listen-peer-urls":["https://162.253.55.74:2380"],"advertise-client-urls":["https://162.253.55.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-09T01:36:59.345395Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-09T01:36:59.345793Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-09T01:36:59.345879Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.74:2380"} {"level":"info","ts":"2026-03-09T01:36:59.726643Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 is starting a new election at term 1"} {"level":"info","ts":"2026-03-09T01:36:59.726749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-09T01:36:59.726774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgPreVoteResp from 30aa8136fb61a722 at term 1"} {"level":"info","ts":"2026-03-09T01:36:59.726798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became candidate at term 2"} {"level":"info","ts":"2026-03-09T01:36:59.726807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 received MsgVoteResp from 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-09T01:36:59.726821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"30aa8136fb61a722 became leader at term 2"} {"level":"info","ts":"2026-03-09T01:36:59.726848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 30aa8136fb61a722 elected leader 30aa8136fb61a722 at term 2"} {"level":"info","ts":"2026-03-09T01:36:59.730524Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:36:59.733552Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"30aa8136fb61a722","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.74:2379]}","request-path":"/0/members/30aa8136fb61a722/attributes","cluster-id":"e7ea3889c4872560","publish-timeout":"7s"} {"level":"info","ts":"2026-03-09T01:36:59.733721Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:36:59.73386Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-09T01:36:59.734914Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7ea3889c4872560","local-member-id":"30aa8136fb61a722","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:36:59.735001Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:36:59.735039Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-09T01:36:59.735105Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-09T01:36:59.735196Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-09T01:36:59.737048Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.74:2379"} {"level":"info","ts":"2026-03-09T01:36:59.737731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-09T01:37:32.345637Z","caller":"traceutil/trace.go:171","msg":"trace[1441286929] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"189.315847ms","start":"2026-03-09T01:37:32.156294Z","end":"2026-03-09T01:37:32.34561Z","steps":["trace[1441286929] 'process raft request' (duration: 189.152083ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:37:36.904791Z","caller":"traceutil/trace.go:171","msg":"trace[1561906655] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"182.961217ms","start":"2026-03-09T01:37:36.721795Z","end":"2026-03-09T01:37:36.904756Z","steps":["trace[1561906655] 'read index received' (duration: 182.66647ms)","trace[1561906655] 'applied index is now lower than readState.Index' (duration: 293.767µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:37:36.904937Z","caller":"traceutil/trace.go:171","msg":"trace[759601766] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"235.335509ms","start":"2026-03-09T01:37:36.669589Z","end":"2026-03-09T01:37:36.904925Z","steps":["trace[759601766] 'process raft request' (duration: 234.99633ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:37:36.905268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.45998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-03-09T01:37:36.905255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.262072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:37:36.905338Z","caller":"traceutil/trace.go:171","msg":"trace[1658652473] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:532; }","duration":"183.566553ms","start":"2026-03-09T01:37:36.721759Z","end":"2026-03-09T01:37:36.905325Z","steps":["trace[1658652473] 'agreement among raft nodes before linearized reading' (duration: 183.339947ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:37:36.905384Z","caller":"traceutil/trace.go:171","msg":"trace[749325071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:532; }","duration":"112.479247ms","start":"2026-03-09T01:37:36.792878Z","end":"2026-03-09T01:37:36.905358Z","steps":["trace[749325071] 'agreement among raft nodes before linearized reading' (duration: 112.225251ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:21.905166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.504147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:21.905309Z","caller":"traceutil/trace.go:171","msg":"trace[1652818065] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"112.732142ms","start":"2026-03-09T01:38:21.792548Z","end":"2026-03-09T01:38:21.90528Z","steps":["trace[1652818065] 'range keys from in-memory index tree' (duration: 112.417555ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:23.275701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.430755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:38:23.275993Z","caller":"traceutil/trace.go:171","msg":"trace[51999192] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"191.29518ms","start":"2026-03-09T01:38:23.084628Z","end":"2026-03-09T01:38:23.275924Z","steps":["trace[51999192] 'process raft request' (duration: 58.979632ms)","trace[51999192] 'compare' (duration: 131.298562ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:34.558708Z","caller":"traceutil/trace.go:171","msg":"trace[691156020] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"195.381493ms","start":"2026-03-09T01:38:34.363298Z","end":"2026-03-09T01:38:34.55868Z","steps":["trace[691156020] 'read index received' (duration: 194.926021ms)","trace[691156020] 'applied index is now lower than readState.Index' (duration: 450.322µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:34.558889Z","caller":"traceutil/trace.go:171","msg":"trace[7244812] transaction","detail":"{read_only:false; response_revision:882; number_of_response:1; }","duration":"217.302437ms","start":"2026-03-09T01:38:34.34151Z","end":"2026-03-09T01:38:34.558812Z","steps":["trace[7244812] 'process raft request' (duration: 216.811705ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:34.558951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.64269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T01:38:34.558995Z","caller":"traceutil/trace.go:171","msg":"trace[1067629091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:882; }","duration":"195.748323ms","start":"2026-03-09T01:38:34.363236Z","end":"2026-03-09T01:38:34.558984Z","steps":["trace[1067629091] 'agreement among raft nodes before linearized reading' (duration: 195.576708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:34.972616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.588862ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:38:34.972713Z","caller":"traceutil/trace.go:171","msg":"trace[1451791285] linearizableReadLoop","detail":"{readStateIndex:916; appliedIndex:915; }","duration":"339.691474ms","start":"2026-03-09T01:38:34.633008Z","end":"2026-03-09T01:38:34.9727Z","steps":["trace[1451791285] 'read index received' (duration: 210.895797ms)","trace[1451791285] 'applied index is now lower than readState.Index' (duration: 128.794877ms)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:34.972973Z","caller":"traceutil/trace.go:171","msg":"trace[1900377896] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"408.523716ms","start":"2026-03-09T01:38:34.56444Z","end":"2026-03-09T01:38:34.972964Z","steps":["trace[1900377896] 'process raft request' (duration: 279.516353ms)","trace[1900377896] 'compare' (duration: 128.49465ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:38:34.973032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:34.564425Z","time spent":"408.575227ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:38:34.973312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.253778ms","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-03-09T01:38:34.973401Z","caller":"traceutil/trace.go:171","msg":"trace[1776901232] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"340.421432ms","start":"2026-03-09T01:38:34.632956Z","end":"2026-03-09T01:38:34.973378Z","steps":["trace[1776901232] 'agreement among raft nodes before linearized reading' (duration: 340.062304ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:34.973452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:34.632926Z","time spent":"340.514696ms","remote":"127.0.0.1:40218","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-03-09T01:38:34.977674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.61306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:34.977709Z","caller":"traceutil/trace.go:171","msg":"trace[66931960] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:883; }","duration":"184.708373ms","start":"2026-03-09T01:38:34.792991Z","end":"2026-03-09T01:38:34.9777Z","steps":["trace[66931960] 'agreement among raft nodes before linearized reading' (duration: 184.634762ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:34.977695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.228962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T01:38:34.977673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.469537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:34.977747Z","caller":"traceutil/trace.go:171","msg":"trace[1204924289] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:883; }","duration":"301.281003ms","start":"2026-03-09T01:38:34.676453Z","end":"2026-03-09T01:38:34.977734Z","steps":["trace[1204924289] 'agreement among raft nodes before linearized reading' (duration: 301.201551ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:34.977757Z","caller":"traceutil/trace.go:171","msg":"trace[1619701740] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:883; }","duration":"318.58586ms","start":"2026-03-09T01:38:34.659158Z","end":"2026-03-09T01:38:34.977744Z","steps":["trace[1619701740] 'agreement among raft nodes before linearized reading' (duration: 318.450087ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:34.977782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:34.676409Z","time spent":"301.363605ms","remote":"127.0.0.1:40064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-03-09T01:38:34.977793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:34.659137Z","time spent":"318.642941ms","remote":"127.0.0.1:40202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"info","ts":"2026-03-09T01:38:36.755939Z","caller":"traceutil/trace.go:171","msg":"trace[479498916] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"186.53788ms","start":"2026-03-09T01:38:36.569378Z","end":"2026-03-09T01:38:36.755916Z","steps":["trace[479498916] 'process raft request' (duration: 186.415166ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:50.392316Z","caller":"traceutil/trace.go:171","msg":"trace[730157966] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"260.906172ms","start":"2026-03-09T01:38:50.131385Z","end":"2026-03-09T01:38:50.392291Z","steps":["trace[730157966] 'process raft request' (duration: 260.759437ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:51.36883Z","caller":"traceutil/trace.go:171","msg":"trace[598498724] linearizableReadLoop","detail":"{readStateIndex:964; appliedIndex:963; }","duration":"159.139236ms","start":"2026-03-09T01:38:51.209646Z","end":"2026-03-09T01:38:51.368786Z","steps":["trace[598498724] 'read index received' (duration: 158.984602ms)","trace[598498724] 'applied index is now lower than readState.Index' (duration: 153.574µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:51.368945Z","caller":"traceutil/trace.go:171","msg":"trace[1755922097] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"251.109163ms","start":"2026-03-09T01:38:51.117808Z","end":"2026-03-09T01:38:51.368917Z","steps":["trace[1755922097] 'process raft request' (duration: 250.831706ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:51.368968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.300241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-09T01:38:51.369016Z","caller":"traceutil/trace.go:171","msg":"trace[282506538] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:928; }","duration":"159.411843ms","start":"2026-03-09T01:38:51.209594Z","end":"2026-03-09T01:38:51.369006Z","steps":["trace[282506538] 'agreement among raft nodes before linearized reading' (duration: 159.30427ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:51.881256Z","caller":"traceutil/trace.go:171","msg":"trace[1982021480] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"201.481867ms","start":"2026-03-09T01:38:51.679751Z","end":"2026-03-09T01:38:51.881233Z","steps":["trace[1982021480] 'process raft request' (duration: 201.316253ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:53.852384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.324077ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5183"} {"level":"info","ts":"2026-03-09T01:38:53.852468Z","caller":"traceutil/trace.go:171","msg":"trace[1972800585] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:950; }","duration":"149.462532ms","start":"2026-03-09T01:38:53.702987Z","end":"2026-03-09T01:38:53.85245Z","steps":["trace[1972800585] 'range keys from in-memory index tree' (duration: 149.151874ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:54.621888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.979539ms","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-03-09T01:38:54.621944Z","caller":"traceutil/trace.go:171","msg":"trace[2139771617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:956; }","duration":"215.085072ms","start":"2026-03-09T01:38:54.406845Z","end":"2026-03-09T01:38:54.62193Z","steps":["trace[2139771617] 'range keys from in-memory index tree' (duration: 214.857366ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:54.621943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.300595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:54.62205Z","caller":"traceutil/trace.go:171","msg":"trace[422446449] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:956; }","duration":"437.479399ms","start":"2026-03-09T01:38:54.184548Z","end":"2026-03-09T01:38:54.622027Z","steps":["trace[422446449] 'count revisions from in-memory index tree' (duration: 437.211602ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:54.622108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:54.184524Z","time spent":"437.57161ms","remote":"127.0.0.1:40092","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"info","ts":"2026-03-09T01:38:54.896205Z","caller":"traceutil/trace.go:171","msg":"trace[369556458] linearizableReadLoop","detail":"{readStateIndex:994; appliedIndex:993; }","duration":"106.445993ms","start":"2026-03-09T01:38:54.789743Z","end":"2026-03-09T01:38:54.896189Z","steps":["trace[369556458] 'read index received' (duration: 106.335541ms)","trace[369556458] 'applied index is now lower than readState.Index' (duration: 109.802µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:54.896284Z","caller":"traceutil/trace.go:171","msg":"trace[1299051760] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"270.586336ms","start":"2026-03-09T01:38:54.625691Z","end":"2026-03-09T01:38:54.896278Z","steps":["trace[1299051760] 'process raft request' (duration: 270.397122ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:54.89639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.640058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:54.896416Z","caller":"traceutil/trace.go:171","msg":"trace[1098547644] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"106.70346ms","start":"2026-03-09T01:38:54.789706Z","end":"2026-03-09T01:38:54.89641Z","steps":["trace[1098547644] 'agreement among raft nodes before linearized reading' (duration: 106.654138ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:55.641708Z","caller":"traceutil/trace.go:171","msg":"trace[159146635] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"164.395559ms","start":"2026-03-09T01:38:55.477278Z","end":"2026-03-09T01:38:55.641674Z","steps":["trace[159146635] 'process raft request' (duration: 164.04341ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:56.047166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.832047ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-09T01:38:56.047276Z","caller":"traceutil/trace.go:171","msg":"trace[1663695540] linearizableReadLoop","detail":"{readStateIndex:999; appliedIndex:998; }","duration":"254.726395ms","start":"2026-03-09T01:38:55.792533Z","end":"2026-03-09T01:38:56.04726Z","steps":["trace[1663695540] 'read index received' (duration: 37.689374ms)","trace[1663695540] 'applied index is now lower than readState.Index' (duration: 217.036001ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:38:56.047396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.865628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:38:56.047429Z","caller":"traceutil/trace.go:171","msg":"trace[1202993880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"254.92702ms","start":"2026-03-09T01:38:55.792492Z","end":"2026-03-09T01:38:56.047419Z","steps":["trace[1202993880] 'agreement among raft nodes before linearized reading' (duration: 254.817167ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:56.04747Z","caller":"traceutil/trace.go:171","msg":"trace[1493484417] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"401.728294ms","start":"2026-03-09T01:38:55.645703Z","end":"2026-03-09T01:38:56.047431Z","steps":["trace[1493484417] 'process raft request' (duration: 184.54453ms)","trace[1493484417] 'compare' (duration: 216.682762ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:38:56.047635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:38:55.64568Z","time spent":"401.854067ms","remote":"127.0.0.1:40218","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-03-09T01:38:57.077502Z","caller":"traceutil/trace.go:171","msg":"trace[1369995236] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"174.536627ms","start":"2026-03-09T01:38:56.90294Z","end":"2026-03-09T01:38:57.077476Z","steps":["trace[1369995236] 'process raft request' (duration: 174.341991ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:57.317329Z","caller":"traceutil/trace.go:171","msg":"trace[1656492970] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1006; }","duration":"132.118143ms","start":"2026-03-09T01:38:57.185188Z","end":"2026-03-09T01:38:57.317306Z","steps":["trace[1656492970] 'read index received' (duration: 131.99872ms)","trace[1656492970] 'applied index is now lower than readState.Index' (duration: 118.453µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:38:57.317463Z","caller":"traceutil/trace.go:171","msg":"trace[1916183094] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"234.771519ms","start":"2026-03-09T01:38:57.082665Z","end":"2026-03-09T01:38:57.317436Z","steps":["trace[1916183094] 'process raft request' (duration: 234.516533ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:38:57.317624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.427131ms","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-03-09T01:38:57.317657Z","caller":"traceutil/trace.go:171","msg":"trace[1972629238] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"132.560554ms","start":"2026-03-09T01:38:57.185087Z","end":"2026-03-09T01:38:57.317648Z","steps":["trace[1972629238] 'agreement among raft nodes before linearized reading' (duration: 132.298727ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:57.427656Z","caller":"traceutil/trace.go:171","msg":"trace[1905935464] transaction","detail":"{read_only:false; response_revision:972; number_of_response:1; }","duration":"106.451323ms","start":"2026-03-09T01:38:57.32118Z","end":"2026-03-09T01:38:57.427631Z","steps":["trace[1905935464] 'process raft request' (duration: 106.389962ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:38:57.427748Z","caller":"traceutil/trace.go:171","msg":"trace[1684962444] transaction","detail":"{read_only:false; response_revision:971; number_of_response:1; }","duration":"186.495279ms","start":"2026-03-09T01:38:57.241217Z","end":"2026-03-09T01:38:57.427712Z","steps":["trace[1684962444] 'process raft request' (duration: 182.278153ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:39:01.544531Z","caller":"traceutil/trace.go:171","msg":"trace[1547837781] linearizableReadLoop","detail":"{readStateIndex:1033; appliedIndex:1032; }","duration":"201.086897ms","start":"2026-03-09T01:39:01.343413Z","end":"2026-03-09T01:39:01.5445Z","steps":["trace[1547837781] 'read index received' (duration: 200.897133ms)","trace[1547837781] 'applied index is now lower than readState.Index' (duration: 188.454µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:39:01.544662Z","caller":"traceutil/trace.go:171","msg":"trace[635747633] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"204.899103ms","start":"2026-03-09T01:39:01.339752Z","end":"2026-03-09T01:39:01.544651Z","steps":["trace[635747633] 'process raft request' (duration: 204.557955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:01.545016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.58951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-03-09T01:39:01.545072Z","caller":"traceutil/trace.go:171","msg":"trace[1287853807] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:995; }","duration":"201.688983ms","start":"2026-03-09T01:39:01.343369Z","end":"2026-03-09T01:39:01.545058Z","steps":["trace[1287853807] 'agreement among raft nodes before linearized reading' (duration: 201.516799ms)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:39:07.750747Z","caller":"traceutil/trace.go:171","msg":"trace[1158328254] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"264.03602ms","start":"2026-03-09T01:39:07.486681Z","end":"2026-03-09T01:39:07.750717Z","steps":["trace[1158328254] 'read index received' (duration: 263.931687ms)","trace[1158328254] 'applied index is now lower than readState.Index' (duration: 103.153µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:39:07.750766Z","caller":"traceutil/trace.go:171","msg":"trace[647804683] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"342.556007ms","start":"2026-03-09T01:39:07.40819Z","end":"2026-03-09T01:39:07.750746Z","steps":["trace[647804683] 'process raft request' (duration: 342.370282ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:07.75093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.236264ms","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-03-09T01:39:07.750978Z","caller":"traceutil/trace.go:171","msg":"trace[2125783979] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:1037; }","duration":"264.338247ms","start":"2026-03-09T01:39:07.48663Z","end":"2026-03-09T01:39:07.750968Z","steps":["trace[2125783979] 'agreement among raft nodes before linearized reading' (duration: 264.180813ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:07.750935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.408174Z","time spent":"342.682209ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:39:08.160208Z","caller":"traceutil/trace.go:171","msg":"trace[179070856] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"540.054634ms","start":"2026-03-09T01:39:07.620123Z","end":"2026-03-09T01:39:08.160177Z","steps":["trace[179070856] 'process raft request' (duration: 539.84856ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:08.160376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.620094Z","time spent":"540.203227ms","remote":"127.0.0.1:40130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6610,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-09T01:39:08.160365Z","caller":"traceutil/trace.go:171","msg":"trace[914513114] linearizableReadLoop","detail":"{readStateIndex:1078; appliedIndex:1077; }","duration":"409.512861ms","start":"2026-03-09T01:39:07.750821Z","end":"2026-03-09T01:39:08.160334Z","steps":["trace[914513114] 'read index received' (duration: 409.297436ms)","trace[914513114] 'applied index is now lower than readState.Index' (duration: 213.795µs)"],"step_count":2} {"level":"info","ts":"2026-03-09T01:39:08.160461Z","caller":"traceutil/trace.go:171","msg":"trace[318904963] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"411.499992ms","start":"2026-03-09T01:39:07.748932Z","end":"2026-03-09T01:39:08.160432Z","steps":["trace[318904963] 'process raft request' (duration: 411.195204ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:08.160695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.395431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T01:39:08.160744Z","caller":"traceutil/trace.go:171","msg":"trace[2014832454] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1039; }","duration":"534.499554ms","start":"2026-03-09T01:39:07.626231Z","end":"2026-03-09T01:39:08.160731Z","steps":["trace[2014832454] 'agreement among raft nodes before linearized reading' (duration: 534.264407ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:08.160746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.648055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-09T01:39:08.160779Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.626211Z","time spent":"534.559785ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-09T01:39:08.160787Z","caller":"traceutil/trace.go:171","msg":"trace[529956962] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"592.728597ms","start":"2026-03-09T01:39:07.568047Z","end":"2026-03-09T01:39:08.160776Z","steps":["trace[529956962] 'agreement among raft nodes before linearized reading' (duration: 592.588932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:08.16082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.568026Z","time spent":"592.786918ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-09T01:39:08.161159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.748914Z","time spent":"411.640494ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-09T01:39:08.164053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.019314ms","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-03-09T01:39:08.164104Z","caller":"traceutil/trace.go:171","msg":"trace[1307665902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1039; }","duration":"412.117656ms","start":"2026-03-09T01:39:07.751972Z","end":"2026-03-09T01:39:08.16409Z","steps":["trace[1307665902] 'agreement among raft nodes before linearized reading' (duration: 411.987393ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:39:08.164136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:39:07.751951Z","time spent":"412.177718ms","remote":"127.0.0.1:40218","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-03-09T01:41:06.33382Z","caller":"traceutil/trace.go:171","msg":"trace[1749677904] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"217.885041ms","start":"2026-03-09T01:41:06.1159Z","end":"2026-03-09T01:41:06.333785Z","steps":["trace[1749677904] 'process raft request' (duration: 215.838659ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:08.93012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":12043360771741567709,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-09T01:41:09.178514Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.026497508s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-09T01:41:09.178895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.162392291s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:525"} {"level":"warn","ts":"2026-03-09T01:41:09.178922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.95247767s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"warn","ts":"2026-03-09T01:41:09.178971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.593539436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-09T01:41:09.179049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.594771256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-03-09T01:41:09.179106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405201743s","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-03-09T01:41:09.179138Z","caller":"traceutil/trace.go:171","msg":"trace[1035404468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1527; }","duration":"1.405281224s","start":"2026-03-09T01:41:07.77385Z","end":"2026-03-09T01:41:09.179132Z","steps":["trace[1035404468] 'range keys from in-memory index tree' (duration: 1.405096621s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.179188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:07.773781Z","time spent":"1.405376728s","remote":"127.0.0.1:40218","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":"info","ts":"2026-03-09T01:41:09.178993Z","caller":"traceutil/trace.go:171","msg":"trace[1965911766] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1527; }","duration":"1.162546566s","start":"2026-03-09T01:41:08.016425Z","end":"2026-03-09T01:41:09.178972Z","steps":["trace[1965911766] 'range keys from in-memory index tree' (duration: 1.162031132s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.179618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.016401Z","time spent":"1.163204123s","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":548,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-03-09T01:41:09.17906Z","caller":"traceutil/trace.go:171","msg":"trace[1655056678] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1527; }","duration":"1.593692199s","start":"2026-03-09T01:41:07.585341Z","end":"2026-03-09T01:41:09.179034Z","steps":["trace[1655056678] 'count revisions from in-memory index tree' (duration: 1.593417512s)"],"step_count":1} {"level":"info","ts":"2026-03-09T01:41:09.179115Z","caller":"traceutil/trace.go:171","msg":"trace[1572191576] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1527; }","duration":"1.594973461s","start":"2026-03-09T01:41:07.584128Z","end":"2026-03-09T01:41:09.179102Z","steps":["trace[1572191576] 'count revisions from in-memory index tree' (duration: 1.594639413s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.180006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:07.584107Z","time spent":"1.595876854s","remote":"127.0.0.1:40402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-03-09T01:41:09.179893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:07.585319Z","time spent":"1.59451795s","remote":"127.0.0.1:57916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"info","ts":"2026-03-09T01:41:09.178994Z","caller":"traceutil/trace.go:171","msg":"trace[1865103309] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1527; }","duration":"1.952610083s","start":"2026-03-09T01:41:07.226367Z","end":"2026-03-09T01:41:09.178977Z","steps":["trace[1865103309] 'range keys from in-memory index tree' (duration: 1.952289383s)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.180144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:07.226345Z","time spent":"1.953788111s","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-03-09T01:41:09.186943Z","caller":"traceutil/trace.go:171","msg":"trace[1357925039] linearizableReadLoop","detail":"{readStateIndex:1591; appliedIndex:1590; }","duration":"757.652007ms","start":"2026-03-09T01:41:08.429268Z","end":"2026-03-09T01:41:09.18692Z","steps":["trace[1357925039] 'read index received' (duration: 749.550791ms)","trace[1357925039] 'applied index is now lower than readState.Index' (duration: 8.100016ms)"],"step_count":2} {"level":"warn","ts":"2026-03-09T01:41:09.187068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"757.79621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:41:09.187089Z","caller":"traceutil/trace.go:171","msg":"trace[863215522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1527; }","duration":"757.890232ms","start":"2026-03-09T01:41:08.429192Z","end":"2026-03-09T01:41:09.187083Z","steps":["trace[863215522] 'agreement among raft nodes before linearized reading' (duration: 757.82757ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.187114Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.429167Z","time spent":"757.941783ms","remote":"127.0.0.1:53748","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-03-09T01:41:09.188422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.057958ms","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-03-09T01:41:09.188573Z","caller":"traceutil/trace.go:171","msg":"trace[1922651038] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1529; }","duration":"404.268873ms","start":"2026-03-09T01:41:08.784282Z","end":"2026-03-09T01:41:09.188551Z","steps":["trace[1922651038] 'agreement among raft nodes before linearized reading' (duration: 403.970556ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.188633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.784261Z","time spent":"404.363624ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-09T01:41:09.188727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.852214ms","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-03-09T01:41:09.188833Z","caller":"traceutil/trace.go:171","msg":"trace[614571898] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1529; }","duration":"320.012448ms","start":"2026-03-09T01:41:08.86878Z","end":"2026-03-09T01:41:09.188792Z","steps":["trace[614571898] 'agreement among raft nodes before linearized reading' (duration: 319.7091ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.188875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.86877Z","time spent":"320.09619ms","remote":"127.0.0.1:40218","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-03-09T01:41:09.188976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.737537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-03-09T01:41:09.189029Z","caller":"traceutil/trace.go:171","msg":"trace[147639671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1529; }","duration":"320.822758ms","start":"2026-03-09T01:41:08.868199Z","end":"2026-03-09T01:41:09.189021Z","steps":["trace[147639671] 'agreement among raft nodes before linearized reading' (duration: 320.716955ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.189076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.868181Z","time spent":"320.88799ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-09T01:41:09.189028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.331187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-09T01:41:09.1893Z","caller":"traceutil/trace.go:171","msg":"trace[1771837767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1529; }","duration":"399.656535ms","start":"2026-03-09T01:41:08.789633Z","end":"2026-03-09T01:41:09.189289Z","steps":["trace[1771837767] 'agreement among raft nodes before linearized reading' (duration: 399.355778ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.189363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.789614Z","time spent":"399.738578ms","remote":"127.0.0.1:53758","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-03-09T01:41:09.189178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.020156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-03-09T01:41:09.189538Z","caller":"traceutil/trace.go:171","msg":"trace[497547442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1529; }","duration":"389.420787ms","start":"2026-03-09T01:41:08.800109Z","end":"2026-03-09T01:41:09.18953Z","steps":["trace[497547442] 'agreement among raft nodes before linearized reading' (duration: 388.945245ms)"],"step_count":1} {"level":"warn","ts":"2026-03-09T01:41:09.189582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-09T01:41:08.800101Z","time spent":"389.471369ms","remote":"127.0.0.1:40218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "}