{"level":"warn","ts":"2026-03-02T21:17:03.272085Z","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-02T21:17:03.272168Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.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://199.204.45.74:2380","--initial-cluster=instance=https://199.204.45.74:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.74:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.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-02T21:17:03.272235Z","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-02T21:17:03.272247Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-03-02T21:17:03.272267Z","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-02T21:17:03.273099Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"]} {"level":"info","ts":"2026-03-02T21:17:03.273189Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.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-02T21:17:03.278816Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.389629ms"} {"level":"info","ts":"2026-03-02T21:17:03.285171Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"89ba38f964b95aa0","cluster-id":"54492f71bf65c400"} {"level":"info","ts":"2026-03-02T21:17:03.285304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=()"} {"level":"info","ts":"2026-03-02T21:17:03.285344Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 0"} {"level":"info","ts":"2026-03-02T21:17:03.285364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 89ba38f964b95aa0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-02T21:17:03.285381Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became follower at term 1"} {"level":"info","ts":"2026-03-02T21:17:03.285431Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"warn","ts":"2026-03-02T21:17:03.293364Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-02T21:17:03.294985Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-02T21:17:03.298669Z","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-02T21:17:03.301614Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"89ba38f964b95aa0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-02T21:17:03.301845Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"89ba38f964b95aa0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-02T21:17:03.302093Z","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-02T21:17:03.302188Z","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-02T21:17:03.302201Z","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-02T21:17:03.303806Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 switched to configuration voters=(9924307372652190368)"} {"level":"info","ts":"2026-03-02T21:17:03.304059Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","added-peer-id":"89ba38f964b95aa0","added-peer-peer-urls":["https://199.204.45.74:2380"]} {"level":"info","ts":"2026-03-02T21:17:03.30576Z","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-02T21:17:03.305843Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-03-02T21:17:03.305873Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.74:2380"} {"level":"info","ts":"2026-03-02T21:17:03.306336Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"89ba38f964b95aa0","initial-advertise-peer-urls":["https://199.204.45.74:2380"],"listen-peer-urls":["https://199.204.45.74:2380"],"advertise-client-urls":["https://199.204.45.74:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.74:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-02T21:17:03.306421Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-02T21:17:03.886464Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 is starting a new election at term 1"} {"level":"info","ts":"2026-03-02T21:17:03.886524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-02T21:17:03.886564Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgPreVoteResp from 89ba38f964b95aa0 at term 1"} {"level":"info","ts":"2026-03-02T21:17:03.886584Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became candidate at term 2"} {"level":"info","ts":"2026-03-02T21:17:03.886596Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 received MsgVoteResp from 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-03-02T21:17:03.886619Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"89ba38f964b95aa0 became leader at term 2"} {"level":"info","ts":"2026-03-02T21:17:03.886633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 89ba38f964b95aa0 elected leader 89ba38f964b95aa0 at term 2"} {"level":"info","ts":"2026-03-02T21:17:03.896758Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:03.896924Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"89ba38f964b95aa0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.74:2379]}","request-path":"/0/members/89ba38f964b95aa0/attributes","cluster-id":"54492f71bf65c400","publish-timeout":"7s"} {"level":"info","ts":"2026-03-02T21:17:03.897081Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:17:03.897114Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-02T21:17:03.897492Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-02T21:17:03.897542Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-02T21:17:03.900776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-02T21:17:03.901072Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.74:2379"} {"level":"info","ts":"2026-03-02T21:17:03.910403Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"54492f71bf65c400","local-member-id":"89ba38f964b95aa0","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:03.910872Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:03.910971Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-03-02T21:17:05.445999Z","caller":"traceutil/trace.go:171","msg":"trace[810278215] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"226.801123ms","start":"2026-03-02T21:17:05.219173Z","end":"2026-03-02T21:17:05.445974Z","steps":["trace[810278215] 'process raft request' (duration: 226.68462ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.44604Z","caller":"traceutil/trace.go:171","msg":"trace[1777604393] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"226.74498ms","start":"2026-03-02T21:17:05.219263Z","end":"2026-03-02T21:17:05.446008Z","steps":["trace[1777604393] 'process raft request' (duration: 226.640207ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446129Z","caller":"traceutil/trace.go:171","msg":"trace[345603078] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:8; }","duration":"216.016565ms","start":"2026-03-02T21:17:05.230088Z","end":"2026-03-02T21:17:05.446104Z","steps":["trace[345603078] 'read index received' (duration: 59.824874ms)","trace[345603078] 'applied index is now lower than readState.Index' (duration: 156.190801ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:05.446234Z","caller":"traceutil/trace.go:171","msg":"trace[1887541052] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"214.646529ms","start":"2026-03-02T21:17:05.231571Z","end":"2026-03-02T21:17:05.446218Z","steps":["trace[1887541052] 'process raft request' (duration: 214.440013ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446247Z","caller":"traceutil/trace.go:171","msg":"trace[1202274302] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"226.895825ms","start":"2026-03-02T21:17:05.219331Z","end":"2026-03-02T21:17:05.446227Z","steps":["trace[1202274302] 'process raft request' (duration: 226.602757ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446402Z","caller":"traceutil/trace.go:171","msg":"trace[319400690] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"226.939426ms","start":"2026-03-02T21:17:05.219428Z","end":"2026-03-02T21:17:05.446367Z","steps":["trace[319400690] 'process raft request' (duration: 226.532515ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446479Z","caller":"traceutil/trace.go:171","msg":"trace[1890181605] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"226.278759ms","start":"2026-03-02T21:17:05.220189Z","end":"2026-03-02T21:17:05.446467Z","steps":["trace[1890181605] 'process raft request' (duration: 225.796446ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.446473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.146102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:05.446477Z","caller":"traceutil/trace.go:171","msg":"trace[802063511] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"213.479239ms","start":"2026-03-02T21:17:05.232943Z","end":"2026-03-02T21:17:05.446422Z","steps":["trace[802063511] 'process raft request' (duration: 213.117869ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446555Z","caller":"traceutil/trace.go:171","msg":"trace[1931970340] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:13; }","duration":"234.258255ms","start":"2026-03-02T21:17:05.212282Z","end":"2026-03-02T21:17:05.44654Z","steps":["trace[1931970340] 'agreement among raft nodes before linearized reading' (duration: 234.102701ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.446711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.827972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-02T21:17:05.446807Z","caller":"traceutil/trace.go:171","msg":"trace[747433760] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:13; }","duration":"251.031638ms","start":"2026-03-02T21:17:05.19576Z","end":"2026-03-02T21:17:05.446792Z","steps":["trace[747433760] 'agreement among raft nodes before linearized reading' (duration: 250.674578ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.446832Z","caller":"traceutil/trace.go:171","msg":"trace[532346911] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"227.998354ms","start":"2026-03-02T21:17:05.218804Z","end":"2026-03-02T21:17:05.446803Z","steps":["trace[532346911] 'process raft request' (duration: 171.454504ms)","trace[532346911] 'compare' (duration: 55.438021ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:05.476919Z","caller":"traceutil/trace.go:171","msg":"trace[1732171306] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"178.012294ms","start":"2026-03-02T21:17:05.298874Z","end":"2026-03-02T21:17:05.476887Z","steps":["trace[1732171306] 'process raft request' (duration: 177.87609ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.477178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.992017ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-03-02T21:17:05.477231Z","caller":"traceutil/trace.go:171","msg":"trace[253862282] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:14; }","duration":"201.138539ms","start":"2026-03-02T21:17:05.27608Z","end":"2026-03-02T21:17:05.477218Z","steps":["trace[253862282] 'agreement among raft nodes before linearized reading' (duration: 201.048127ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.477388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.753481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:05.477473Z","caller":"traceutil/trace.go:171","msg":"trace[2039604094] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14; }","duration":"245.861574ms","start":"2026-03-02T21:17:05.231601Z","end":"2026-03-02T21:17:05.477463Z","steps":["trace[2039604094] 'agreement among raft nodes before linearized reading' (duration: 245.762062ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.677205Z","caller":"traceutil/trace.go:171","msg":"trace[1769891348] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"225.367196ms","start":"2026-03-02T21:17:05.451788Z","end":"2026-03-02T21:17:05.677155Z","steps":["trace[1769891348] 'process raft request' (duration: 225.251442ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.677245Z","caller":"traceutil/trace.go:171","msg":"trace[677549134] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:17; }","duration":"200.36551ms","start":"2026-03-02T21:17:05.476864Z","end":"2026-03-02T21:17:05.677229Z","steps":["trace[677549134] 'read index received' (duration: 179.143242ms)","trace[677549134] 'applied index is now lower than readState.Index' (duration: 21.221218ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:05.677246Z","caller":"traceutil/trace.go:171","msg":"trace[1245500074] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"225.192531ms","start":"2026-03-02T21:17:05.452015Z","end":"2026-03-02T21:17:05.677207Z","steps":["trace[1245500074] 'process raft request' (duration: 225.085067ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.677291Z","caller":"traceutil/trace.go:171","msg":"trace[1268652258] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"225.608632ms","start":"2026-03-02T21:17:05.451647Z","end":"2026-03-02T21:17:05.677256Z","steps":["trace[1268652258] 'process raft request' (duration: 204.379493ms)","trace[1268652258] 'compare' (duration: 20.869119ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:05.677396Z","caller":"traceutil/trace.go:171","msg":"trace[691628611] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"225.459497ms","start":"2026-03-02T21:17:05.451927Z","end":"2026-03-02T21:17:05.677387Z","steps":["trace[691628611] 'process raft request' (duration: 225.146909ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.677491Z","caller":"traceutil/trace.go:171","msg":"trace[483647240] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"225.447308ms","start":"2026-03-02T21:17:05.452034Z","end":"2026-03-02T21:17:05.677481Z","steps":["trace[483647240] 'process raft request' (duration: 225.1372ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.67756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.363091ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:05.677599Z","caller":"traceutil/trace.go:171","msg":"trace[1508484192] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"225.56596ms","start":"2026-03-02T21:17:05.452022Z","end":"2026-03-02T21:17:05.677588Z","steps":["trace[1508484192] 'process raft request' (duration: 225.112479ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.677576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.561882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-03-02T21:17:05.677635Z","caller":"traceutil/trace.go:171","msg":"trace[1027061519] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:20; }","duration":"226.481574ms","start":"2026-03-02T21:17:05.451133Z","end":"2026-03-02T21:17:05.677614Z","steps":["trace[1027061519] 'agreement among raft nodes before linearized reading' (duration: 226.291169ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.677576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.403167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-6mph6\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-03-02T21:17:05.67774Z","caller":"traceutil/trace.go:171","msg":"trace[1707193636] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-6mph6; range_end:; response_count:1; response_revision:20; }","duration":"227.604812ms","start":"2026-03-02T21:17:05.450112Z","end":"2026-03-02T21:17:05.677716Z","steps":["trace[1707193636] 'agreement among raft nodes before linearized reading' (duration: 227.357436ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.677766Z","caller":"traceutil/trace.go:171","msg":"trace[1448214163] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:20; }","duration":"227.709695ms","start":"2026-03-02T21:17:05.449959Z","end":"2026-03-02T21:17:05.677669Z","steps":["trace[1448214163] 'agreement among raft nodes before linearized reading' (duration: 227.5137ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.677769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.818636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:05.67794Z","caller":"traceutil/trace.go:171","msg":"trace[1123969619] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:20; }","duration":"226.008392ms","start":"2026-03-02T21:17:05.451906Z","end":"2026-03-02T21:17:05.677876Z","steps":["trace[1123969619] 'agreement among raft nodes before linearized reading' (duration: 225.765075ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.905872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.76516ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-03-02T21:17:05.906028Z","caller":"traceutil/trace.go:171","msg":"trace[622575280] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:23; }","duration":"228.728342ms","start":"2026-03-02T21:17:05.677285Z","end":"2026-03-02T21:17:05.906013Z","steps":["trace[622575280] 'read index received' (duration: 117.320597ms)","trace[622575280] 'applied index is now lower than readState.Index' (duration: 111.406895ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:05.906157Z","caller":"traceutil/trace.go:171","msg":"trace[1174628079] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"425.232554ms","start":"2026-03-02T21:17:05.480865Z","end":"2026-03-02T21:17:05.906098Z","steps":["trace[1174628079] 'process raft request' (duration: 425.0974ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.906212Z","caller":"traceutil/trace.go:171","msg":"trace[117480328] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"427.005969ms","start":"2026-03-02T21:17:05.479163Z","end":"2026-03-02T21:17:05.906169Z","steps":["trace[117480328] 'process raft request' (duration: 315.424988ms)","trace[117480328] 'compare' (duration: 110.649586ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:05.906315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.803006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:05.906369Z","caller":"traceutil/trace.go:171","msg":"trace[1704847482] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:22; }","duration":"385.931739ms","start":"2026-03-02T21:17:05.520419Z","end":"2026-03-02T21:17:05.90635Z","steps":["trace[1704847482] 'agreement among raft nodes before linearized reading' (duration: 385.693663ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:05.906439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:05.520404Z","time spent":"386.019271ms","remote":"127.0.0.1:33168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-03-02T21:17:05.906494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:05.480847Z","time spent":"425.431909ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:17:05.906626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:05.47915Z","time spent":"427.194083ms","remote":"127.0.0.1:33132","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":357,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:17:05.908257Z","caller":"traceutil/trace.go:171","msg":"trace[1380685933] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"225.51811ms","start":"2026-03-02T21:17:05.682724Z","end":"2026-03-02T21:17:05.908242Z","steps":["trace[1380685933] 'process raft request' (duration: 225.48286ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.908644Z","caller":"traceutil/trace.go:171","msg":"trace[501039320] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"226.507276ms","start":"2026-03-02T21:17:05.682122Z","end":"2026-03-02T21:17:05.90863Z","steps":["trace[501039320] 'process raft request' (duration: 225.941471ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.908674Z","caller":"traceutil/trace.go:171","msg":"trace[632835477] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"226.860085ms","start":"2026-03-02T21:17:05.68178Z","end":"2026-03-02T21:17:05.90864Z","steps":["trace[632835477] 'process raft request' (duration: 226.208318ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.90883Z","caller":"traceutil/trace.go:171","msg":"trace[791338191] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"226.3033ms","start":"2026-03-02T21:17:05.682513Z","end":"2026-03-02T21:17:05.908816Z","steps":["trace[791338191] 'process raft request' (duration: 225.615813ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.908845Z","caller":"traceutil/trace.go:171","msg":"trace[1238628537] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"226.708951ms","start":"2026-03-02T21:17:05.682121Z","end":"2026-03-02T21:17:05.90883Z","steps":["trace[1238628537] 'process raft request' (duration: 225.975712ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.908887Z","caller":"traceutil/trace.go:171","msg":"trace[169252085] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"229.204816ms","start":"2026-03-02T21:17:05.67966Z","end":"2026-03-02T21:17:05.908865Z","steps":["trace[169252085] 'process raft request' (duration: 228.21531ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.909082Z","caller":"traceutil/trace.go:171","msg":"trace[751439003] transaction","detail":"{read_only:false; response_revision:29; number_of_response:1; }","duration":"226.489996ms","start":"2026-03-02T21:17:05.682578Z","end":"2026-03-02T21:17:05.909068Z","steps":["trace[751439003] 'process raft request' (duration: 225.575712ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.909124Z","caller":"traceutil/trace.go:171","msg":"trace[1856848349] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"226.520017ms","start":"2026-03-02T21:17:05.682596Z","end":"2026-03-02T21:17:05.909116Z","steps":["trace[1856848349] 'process raft request' (duration: 225.581633ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:05.908677Z","caller":"traceutil/trace.go:171","msg":"trace[2137527087] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"226.608928ms","start":"2026-03-02T21:17:05.682051Z","end":"2026-03-02T21:17:05.90866Z","steps":["trace[2137527087] 'process raft request' (duration: 225.980871ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:06.034507Z","caller":"traceutil/trace.go:171","msg":"trace[379057722] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"121.575997ms","start":"2026-03-02T21:17:05.912904Z","end":"2026-03-02T21:17:06.03448Z","steps":["trace[379057722] 'process raft request' (duration: 121.421023ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:06.034489Z","caller":"traceutil/trace.go:171","msg":"trace[166933646] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"121.69443ms","start":"2026-03-02T21:17:05.912758Z","end":"2026-03-02T21:17:06.034452Z","steps":["trace[166933646] 'process raft request' (duration: 96.474649ms)","trace[166933646] 'compare' (duration: 24.909402ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:06.034598Z","caller":"traceutil/trace.go:171","msg":"trace[269280300] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"121.641338ms","start":"2026-03-02T21:17:05.912948Z","end":"2026-03-02T21:17:06.034589Z","steps":["trace[269280300] 'process raft request' (duration: 121.451604ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:06.034579Z","caller":"traceutil/trace.go:171","msg":"trace[669917167] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"121.587107ms","start":"2026-03-02T21:17:05.912939Z","end":"2026-03-02T21:17:06.034526Z","steps":["trace[669917167] 'process raft request' (duration: 121.414543ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:06.034549Z","caller":"traceutil/trace.go:171","msg":"trace[958179817] linearizableReadLoop","detail":"{readStateIndex:40; appliedIndex:34; }","duration":"120.990692ms","start":"2026-03-02T21:17:05.913538Z","end":"2026-03-02T21:17:06.034529Z","steps":["trace[958179817] 'read index received' (duration: 56.617061ms)","trace[958179817] 'applied index is now lower than readState.Index' (duration: 64.372341ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:06.034527Z","caller":"traceutil/trace.go:171","msg":"trace[1075670402] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"121.652308ms","start":"2026-03-02T21:17:05.912848Z","end":"2026-03-02T21:17:06.034501Z","steps":["trace[1075670402] 'process raft request' (duration: 121.440383ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:06.034762Z","caller":"traceutil/trace.go:171","msg":"trace[1066245244] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"121.172696ms","start":"2026-03-02T21:17:05.913569Z","end":"2026-03-02T21:17:06.034742Z","steps":["trace[1066245244] 'process raft request' (duration: 120.880149ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:06.035038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.125635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-03-02T21:17:06.035128Z","caller":"traceutil/trace.go:171","msg":"trace[223684205] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:38; }","duration":"121.602277ms","start":"2026-03-02T21:17:05.913506Z","end":"2026-03-02T21:17:06.035108Z","steps":["trace[223684205] 'agreement among raft nodes before linearized reading' (duration: 121.076003ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:08.712138Z","caller":"traceutil/trace.go:171","msg":"trace[1374081294] transaction","detail":"{read_only:false; response_revision:249; number_of_response:1; }","duration":"109.29488ms","start":"2026-03-02T21:17:08.602805Z","end":"2026-03-02T21:17:08.7121Z","steps":["trace[1374081294] 'process raft request' (duration: 108.699474ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:08.893796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.916322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:17:08.89405Z","caller":"traceutil/trace.go:171","msg":"trace[362239574] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"172.399197ms","start":"2026-03-02T21:17:08.721605Z","end":"2026-03-02T21:17:08.894004Z","steps":["trace[362239574] 'process raft request' (duration: 66.197588ms)","trace[362239574] 'compare' (duration: 105.716087ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:08.894272Z","caller":"traceutil/trace.go:171","msg":"trace[1048141779] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"169.931814ms","start":"2026-03-02T21:17:08.72426Z","end":"2026-03-02T21:17:08.894192Z","steps":["trace[1048141779] 'process raft request' (duration: 169.658856ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:08.90569Z","caller":"traceutil/trace.go:171","msg":"trace[289281603] transaction","detail":"{read_only:false; response_revision:257; number_of_response:1; }","duration":"135.704912ms","start":"2026-03-02T21:17:08.76996Z","end":"2026-03-02T21:17:08.905665Z","steps":["trace[289281603] 'process raft request' (duration: 135.514767ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:09.081868Z","caller":"traceutil/trace.go:171","msg":"trace[425946136] linearizableReadLoop","detail":"{readStateIndex:269; appliedIndex:268; }","duration":"109.3038ms","start":"2026-03-02T21:17:08.972538Z","end":"2026-03-02T21:17:09.081841Z","steps":["trace[425946136] 'read index received' (duration: 40.618998ms)","trace[425946136] 'applied index is now lower than readState.Index' (duration: 68.683742ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:09.082027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.472334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:17:09.08207Z","caller":"traceutil/trace.go:171","msg":"trace[175430610] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:261; }","duration":"109.587738ms","start":"2026-03-02T21:17:08.97247Z","end":"2026-03-02T21:17:09.082058Z","steps":["trace[175430610] 'agreement among raft nodes before linearized reading' (duration: 109.470805ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:09.082057Z","caller":"traceutil/trace.go:171","msg":"trace[1857102504] transaction","detail":"{read_only:false; response_revision:261; number_of_response:1; }","duration":"128.310411ms","start":"2026-03-02T21:17:08.953702Z","end":"2026-03-02T21:17:09.082012Z","steps":["trace[1857102504] 'process raft request' (duration: 59.557897ms)","trace[1857102504] 'compare' (duration: 68.413115ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:09.36599Z","caller":"traceutil/trace.go:171","msg":"trace[600512063] linearizableReadLoop","detail":"{readStateIndex:271; appliedIndex:270; }","duration":"229.03427ms","start":"2026-03-02T21:17:09.136928Z","end":"2026-03-02T21:17:09.365962Z","steps":["trace[600512063] 'read index received' (duration: 18.714833ms)","trace[600512063] 'applied index is now lower than readState.Index' (duration: 210.318447ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:09.366116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.177864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:17:09.366202Z","caller":"traceutil/trace.go:171","msg":"trace[136884863] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:263; }","duration":"229.297316ms","start":"2026-03-02T21:17:09.136869Z","end":"2026-03-02T21:17:09.366167Z","steps":["trace[136884863] 'agreement among raft nodes before linearized reading' (duration: 229.170573ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:09.366497Z","caller":"traceutil/trace.go:171","msg":"trace[680071773] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"277.658083ms","start":"2026-03-02T21:17:09.088823Z","end":"2026-03-02T21:17:09.366482Z","steps":["trace[680071773] 'process raft request' (duration: 271.352421ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:09.877644Z","caller":"traceutil/trace.go:171","msg":"trace[1389583270] linearizableReadLoop","detail":"{readStateIndex:273; appliedIndex:272; }","duration":"504.166729ms","start":"2026-03-02T21:17:09.373419Z","end":"2026-03-02T21:17:09.877586Z","steps":["trace[1389583270] 'read index received' (duration: 67.432731ms)","trace[1389583270] 'applied index is now lower than readState.Index' (duration: 436.732918ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:09.877697Z","caller":"traceutil/trace.go:171","msg":"trace[78781749] transaction","detail":"{read_only:false; response_revision:265; number_of_response:1; }","duration":"504.701362ms","start":"2026-03-02T21:17:09.372971Z","end":"2026-03-02T21:17:09.877672Z","steps":["trace[78781749] 'process raft request' (duration: 433.079754ms)","trace[78781749] 'compare' (duration: 71.242429ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:09.877804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"504.359544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-03-02T21:17:09.877961Z","caller":"traceutil/trace.go:171","msg":"trace[423419458] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:265; }","duration":"504.503497ms","start":"2026-03-02T21:17:09.373399Z","end":"2026-03-02T21:17:09.877902Z","steps":["trace[423419458] 'agreement among raft nodes before linearized reading' (duration: 504.327713ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:09.878034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.373392Z","time spent":"504.60849ms","remote":"127.0.0.1:33138","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-03-02T21:17:09.878035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.37296Z","time spent":"504.796745ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-02T21:17:10.305746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.86447ms","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-02T21:17:10.305799Z","caller":"traceutil/trace.go:171","msg":"trace[1297427628] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:265; }","duration":"527.966973ms","start":"2026-03-02T21:17:09.777822Z","end":"2026-03-02T21:17:10.305789Z","steps":["trace[1297427628] 'agreement among raft nodes before linearized reading' (duration: 527.86064ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.305834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.777804Z","time spent":"528.021943ms","remote":"127.0.0.1:33268","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-03-02T21:17:10.306032Z","caller":"traceutil/trace.go:171","msg":"trace[115678014] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"770.5004ms","start":"2026-03-02T21:17:09.535491Z","end":"2026-03-02T21:17:10.305991Z","steps":["trace[115678014] 'process raft request' (duration: 770.07958ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.306462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.535478Z","time spent":"770.664455ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:17:10.306449Z","caller":"traceutil/trace.go:171","msg":"trace[901177537] transaction","detail":"{read_only:false; number_of_response:0; response_revision:265; }","duration":"771.281671ms","start":"2026-03-02T21:17:09.535079Z","end":"2026-03-02T21:17:10.306361Z","steps":["trace[901177537] 'process raft request' (duration: 770.410939ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.306497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"761.898528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-02T21:17:10.30653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.993164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:3974"} {"level":"info","ts":"2026-03-02T21:17:10.306572Z","caller":"traceutil/trace.go:171","msg":"trace[301440705] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:265; }","duration":"762.125585ms","start":"2026-03-02T21:17:09.544425Z","end":"2026-03-02T21:17:10.30655Z","steps":["trace[301440705] 'agreement among raft nodes before linearized reading' (duration: 761.692264ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.306579Z","caller":"traceutil/trace.go:171","msg":"trace[1793528746] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:265; }","duration":"773.088057ms","start":"2026-03-02T21:17:09.533478Z","end":"2026-03-02T21:17:10.306566Z","steps":["trace[1793528746] 'agreement among raft nodes before linearized reading' (duration: 772.995275ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.306618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.533457Z","time spent":"773.153969ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":3997,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"warn","ts":"2026-03-02T21:17:10.306626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.544409Z","time spent":"762.201897ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-02T21:17:10.306617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.535063Z","time spent":"771.474616ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:17:10.305622Z","caller":"traceutil/trace.go:171","msg":"trace[225618303] linearizableReadLoop","detail":"{readStateIndex:275; appliedIndex:273; }","duration":"427.87744ms","start":"2026-03-02T21:17:09.877722Z","end":"2026-03-02T21:17:10.3056Z","steps":["trace[225618303] 'read index received' (duration: 427.774327ms)","trace[225618303] 'applied index is now lower than readState.Index' (duration: 102.173µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:10.306905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.261944ms","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-03-02T21:17:10.307017Z","caller":"traceutil/trace.go:171","msg":"trace[2029181945] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:265; }","duration":"863.422899ms","start":"2026-03-02T21:17:09.443579Z","end":"2026-03-02T21:17:10.307002Z","steps":["trace[2029181945] 'agreement among raft nodes before linearized reading' (duration: 863.10544ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.307163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.443552Z","time spent":"863.592733ms","remote":"127.0.0.1:33268","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":"info","ts":"2026-03-02T21:17:10.381459Z","caller":"traceutil/trace.go:171","msg":"trace[1297700925] transaction","detail":"{read_only:false; response_revision:266; number_of_response:1; }","duration":"499.657343ms","start":"2026-03-02T21:17:09.881682Z","end":"2026-03-02T21:17:10.381339Z","steps":["trace[1297700925] 'process raft request' (duration: 499.52796ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.381794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:09.881661Z","time spent":"499.890168ms","remote":"127.0.0.1:33224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:17:10.479009Z","caller":"traceutil/trace.go:171","msg":"trace[1149408465] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"167.729327ms","start":"2026-03-02T21:17:10.311256Z","end":"2026-03-02T21:17:10.478986Z","steps":["trace[1149408465] 'process raft request' (duration: 167.651045ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.47934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.323061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:3466"} {"level":"info","ts":"2026-03-02T21:17:10.479371Z","caller":"traceutil/trace.go:171","msg":"trace[899221448] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:270; }","duration":"171.391662ms","start":"2026-03-02T21:17:10.307972Z","end":"2026-03-02T21:17:10.479363Z","steps":["trace[899221448] 'agreement among raft nodes before linearized reading' (duration: 171.30603ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.479454Z","caller":"traceutil/trace.go:171","msg":"trace[9671702] transaction","detail":"{read_only:false; response_revision:270; number_of_response:1; }","duration":"155.40081ms","start":"2026-03-02T21:17:10.324037Z","end":"2026-03-02T21:17:10.479438Z","steps":["trace[9671702] 'process raft request' (duration: 155.109033ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.479046Z","caller":"traceutil/trace.go:171","msg":"trace[423984412] transaction","detail":"{read_only:false; response_revision:267; number_of_response:1; }","duration":"168.359664ms","start":"2026-03-02T21:17:10.310658Z","end":"2026-03-02T21:17:10.479017Z","steps":["trace[423984412] 'process raft request' (duration: 168.127338ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.479234Z","caller":"traceutil/trace.go:171","msg":"trace[595834625] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"167.947113ms","start":"2026-03-02T21:17:10.311256Z","end":"2026-03-02T21:17:10.479203Z","steps":["trace[595834625] 'process raft request' (duration: 167.696226ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.637324Z","caller":"traceutil/trace.go:171","msg":"trace[906736569] linearizableReadLoop","detail":"{readStateIndex:281; appliedIndex:280; }","duration":"153.272985ms","start":"2026-03-02T21:17:10.484034Z","end":"2026-03-02T21:17:10.637307Z","steps":["trace[906736569] 'read index received' (duration: 143.491972ms)","trace[906736569] 'applied index is now lower than readState.Index' (duration: 9.780023ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:10.637407Z","caller":"traceutil/trace.go:171","msg":"trace[224072271] transaction","detail":"{read_only:false; response_revision:271; number_of_response:1; }","duration":"153.423759ms","start":"2026-03-02T21:17:10.483929Z","end":"2026-03-02T21:17:10.637352Z","steps":["trace[224072271] 'process raft request' (duration: 143.628996ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:10.637608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.51066ms","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-02T21:17:10.637725Z","caller":"traceutil/trace.go:171","msg":"trace[2023265404] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:271; }","duration":"153.681924ms","start":"2026-03-02T21:17:10.484017Z","end":"2026-03-02T21:17:10.637699Z","steps":["trace[2023265404] 'agreement among raft nodes before linearized reading' (duration: 153.368047ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.826531Z","caller":"traceutil/trace.go:171","msg":"trace[1180367985] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"185.68176ms","start":"2026-03-02T21:17:10.640824Z","end":"2026-03-02T21:17:10.826505Z","steps":["trace[1180367985] 'process raft request' (duration: 185.636599ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.826951Z","caller":"traceutil/trace.go:171","msg":"trace[1601267780] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"337.96807ms","start":"2026-03-02T21:17:10.48897Z","end":"2026-03-02T21:17:10.826938Z","steps":["trace[1601267780] 'process raft request' (duration: 337.411716ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:10.827288Z","caller":"traceutil/trace.go:171","msg":"trace[1579432757] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"342.272301ms","start":"2026-03-02T21:17:10.484963Z","end":"2026-03-02T21:17:10.827235Z","steps":["trace[1579432757] 'process raft request' (duration: 263.716634ms)","trace[1579432757] 'compare' (duration: 77.51731ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:17:10.827377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:10.484945Z","time spent":"342.392495ms","remote":"127.0.0.1:33224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-02T21:17:10.827246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:10.488962Z","time spent":"338.050143ms","remote":"127.0.0.1:33200","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:17:42.761293Z","caller":"traceutil/trace.go:171","msg":"trace[1878575828] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"112.326782ms","start":"2026-03-02T21:17:42.648945Z","end":"2026-03-02T21:17:42.761272Z","steps":["trace[1878575828] 'process raft request' (duration: 94.938405ms)","trace[1878575828] 'compare' (duration: 17.240373ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:42.761503Z","caller":"traceutil/trace.go:171","msg":"trace[1174037360] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"103.155055ms","start":"2026-03-02T21:17:42.658315Z","end":"2026-03-02T21:17:42.76147Z","steps":["trace[1174037360] 'process raft request' (duration: 102.9132ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:43.175878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.453839ms","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-02T21:17:43.175989Z","caller":"traceutil/trace.go:171","msg":"trace[2059239633] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:493; }","duration":"210.608733ms","start":"2026-03-02T21:17:42.96535Z","end":"2026-03-02T21:17:43.175959Z","steps":["trace[2059239633] 'range keys from in-memory index tree' (duration: 210.354636ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:43.509836Z","caller":"traceutil/trace.go:171","msg":"trace[348969743] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"330.403005ms","start":"2026-03-02T21:17:43.179412Z","end":"2026-03-02T21:17:43.509815Z","steps":["trace[348969743] 'process raft request' (duration: 330.293373ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:43.509967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:17:43.179393Z","time spent":"330.502628ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-02T21:17:44.90255Z","caller":"traceutil/trace.go:171","msg":"trace[1761396962] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"131.540264ms","start":"2026-03-02T21:17:44.770985Z","end":"2026-03-02T21:17:44.902525Z","steps":["trace[1761396962] 'process raft request' (duration: 131.38504ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:48.553202Z","caller":"traceutil/trace.go:171","msg":"trace[763569374] linearizableReadLoop","detail":"{readStateIndex:584; appliedIndex:583; }","duration":"137.99299ms","start":"2026-03-02T21:17:48.415151Z","end":"2026-03-02T21:17:48.553144Z","steps":["trace[763569374] 'read index received' (duration: 133.640558ms)","trace[763569374] 'applied index is now lower than readState.Index' (duration: 4.351492ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:17:48.553301Z","caller":"traceutil/trace.go:171","msg":"trace[325925932] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"193.177836ms","start":"2026-03-02T21:17:48.360083Z","end":"2026-03-02T21:17:48.553261Z","steps":["trace[325925932] 'process raft request' (duration: 191.253776ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:17:48.553394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.220945ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-03-02T21:17:48.553438Z","caller":"traceutil/trace.go:171","msg":"trace[929121370] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:565; }","duration":"138.329788ms","start":"2026-03-02T21:17:48.415097Z","end":"2026-03-02T21:17:48.553427Z","steps":["trace[929121370] 'agreement among raft nodes before linearized reading' (duration: 138.174084ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:17:55.899444Z","caller":"traceutil/trace.go:171","msg":"trace[674907414] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"140.263398ms","start":"2026-03-02T21:17:55.759163Z","end":"2026-03-02T21:17:55.899426Z","steps":["trace[674907414] 'process raft request' (duration: 134.549371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:01.3312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.963075ms","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-02T21:18:01.331278Z","caller":"traceutil/trace.go:171","msg":"trace[1728622893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:676; }","duration":"362.086708ms","start":"2026-03-02T21:18:00.969171Z","end":"2026-03-02T21:18:01.331258Z","steps":["trace[1728622893] 'range keys from in-memory index tree' (duration: 361.77651ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:01.331334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:18:00.969149Z","time spent":"362.17071ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-02T21:18:01.7412Z","caller":"traceutil/trace.go:171","msg":"trace[1409105541] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"154.332889ms","start":"2026-03-02T21:18:01.586843Z","end":"2026-03-02T21:18:01.741176Z","steps":["trace[1409105541] 'process raft request' (duration: 154.192585ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:04.314283Z","caller":"traceutil/trace.go:171","msg":"trace[2104840681] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"130.917458ms","start":"2026-03-02T21:18:04.183342Z","end":"2026-03-02T21:18:04.31426Z","steps":["trace[2104840681] 'process raft request' (duration: 130.767203ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:27.19026Z","caller":"traceutil/trace.go:171","msg":"trace[994944115] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"234.928661ms","start":"2026-03-02T21:18:26.955307Z","end":"2026-03-02T21:18:27.190236Z","steps":["trace[994944115] 'process raft request' (duration: 234.738767ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:27.202483Z","caller":"traceutil/trace.go:171","msg":"trace[1899886165] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"246.025683ms","start":"2026-03-02T21:18:26.956434Z","end":"2026-03-02T21:18:27.20246Z","steps":["trace[1899886165] 'process raft request' (duration: 245.907179ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:39.472527Z","caller":"traceutil/trace.go:171","msg":"trace[1008493569] linearizableReadLoop","detail":"{readStateIndex:870; appliedIndex:869; }","duration":"180.811135ms","start":"2026-03-02T21:18:39.291693Z","end":"2026-03-02T21:18:39.472504Z","steps":["trace[1008493569] 'read index received' (duration: 180.521319ms)","trace[1008493569] 'applied index is now lower than readState.Index' (duration: 288.666µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:39.472603Z","caller":"traceutil/trace.go:171","msg":"trace[1415443184] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"208.244618ms","start":"2026-03-02T21:18:39.264327Z","end":"2026-03-02T21:18:39.472572Z","steps":["trace[1415443184] 'process raft request' (duration: 207.964001ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:39.472724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.00686ms","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-02T21:18:39.472773Z","caller":"traceutil/trace.go:171","msg":"trace[667890862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:838; }","duration":"181.094362ms","start":"2026-03-02T21:18:39.291663Z","end":"2026-03-02T21:18:39.472757Z","steps":["trace[667890862] 'agreement among raft nodes before linearized reading' (duration: 180.938219ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:44.953298Z","caller":"traceutil/trace.go:171","msg":"trace[1965066414] transaction","detail":"{read_only:false; response_revision:853; number_of_response:1; }","duration":"288.803863ms","start":"2026-03-02T21:18:44.664469Z","end":"2026-03-02T21:18:44.953273Z","steps":["trace[1965066414] 'process raft request' (duration: 288.662919ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:18:45.685606Z","caller":"traceutil/trace.go:171","msg":"trace[1404783135] linearizableReadLoop","detail":"{readStateIndex:887; appliedIndex:886; }","duration":"141.238977ms","start":"2026-03-02T21:18:45.544345Z","end":"2026-03-02T21:18:45.685584Z","steps":["trace[1404783135] 'read index received' (duration: 141.056761ms)","trace[1404783135] 'applied index is now lower than readState.Index' (duration: 181.156µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:18:45.685744Z","caller":"traceutil/trace.go:171","msg":"trace[137787445] transaction","detail":"{read_only:false; response_revision:854; number_of_response:1; }","duration":"146.396846ms","start":"2026-03-02T21:18:45.539338Z","end":"2026-03-02T21:18:45.685735Z","steps":["trace[137787445] 'process raft request' (duration: 146.079518ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:18:45.686248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.892292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T21:18:45.686305Z","caller":"traceutil/trace.go:171","msg":"trace[1883960667] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:854; }","duration":"141.974106ms","start":"2026-03-02T21:18:45.544317Z","end":"2026-03-02T21:18:45.686291Z","steps":["trace[1883960667] 'agreement among raft nodes before linearized reading' (duration: 141.824601ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:01.952199Z","caller":"traceutil/trace.go:171","msg":"trace[1840866711] linearizableReadLoop","detail":"{readStateIndex:963; appliedIndex:962; }","duration":"109.080044ms","start":"2026-03-02T21:19:01.843095Z","end":"2026-03-02T21:19:01.952175Z","steps":["trace[1840866711] 'read index received' (duration: 108.92738ms)","trace[1840866711] 'applied index is now lower than readState.Index' (duration: 151.484µs)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:01.952346Z","caller":"traceutil/trace.go:171","msg":"trace[62318191] transaction","detail":"{read_only:false; response_revision:927; number_of_response:1; }","duration":"118.639437ms","start":"2026-03-02T21:19:01.833695Z","end":"2026-03-02T21:19:01.952334Z","steps":["trace[62318191] 'process raft request' (duration: 118.291888ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:01.952659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.548416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-03-02T21:19:01.952741Z","caller":"traceutil/trace.go:171","msg":"trace[1418639861] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:927; }","duration":"109.671829ms","start":"2026-03-02T21:19:01.84305Z","end":"2026-03-02T21:19:01.952722Z","steps":["trace[1418639861] 'agreement among raft nodes before linearized reading' (duration: 109.468134ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:02.016271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.489693ms","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-02T21:19:02.01639Z","caller":"traceutil/trace.go:171","msg":"trace[1284077329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:928; }","duration":"101.636276ms","start":"2026-03-02T21:19:01.914703Z","end":"2026-03-02T21:19:02.016339Z","steps":["trace[1284077329] 'agreement among raft nodes before linearized reading' (duration: 101.354ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:02.216707Z","caller":"traceutil/trace.go:171","msg":"trace[1390958444] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"195.550487ms","start":"2026-03-02T21:19:02.021108Z","end":"2026-03-02T21:19:02.216659Z","steps":["trace[1390958444] 'process raft request' (duration: 126.178606ms)","trace[1390958444] 'compare' (duration: 69.092035ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:02.937524Z","caller":"traceutil/trace.go:171","msg":"trace[99603924] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"136.529627ms","start":"2026-03-02T21:19:02.800977Z","end":"2026-03-02T21:19:02.937507Z","steps":["trace[99603924] 'read index received' (duration: 136.379454ms)","trace[99603924] 'applied index is now lower than readState.Index' (duration: 149.563µs)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:02.937645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.65038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-02T21:19:02.937678Z","caller":"traceutil/trace.go:171","msg":"trace[397094561] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:0; response_revision:932; }","duration":"136.722752ms","start":"2026-03-02T21:19:02.800945Z","end":"2026-03-02T21:19:02.937668Z","steps":["trace[397094561] 'agreement among raft nodes before linearized reading' (duration: 136.6403ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:02.937866Z","caller":"traceutil/trace.go:171","msg":"trace[323981044] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"212.729211ms","start":"2026-03-02T21:19:02.725117Z","end":"2026-03-02T21:19:02.937846Z","steps":["trace[323981044] 'process raft request' (duration: 212.288531ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:03.557242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.378261ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:19:03.557349Z","caller":"traceutil/trace.go:171","msg":"trace[1959956784] linearizableReadLoop","detail":"{readStateIndex:970; appliedIndex:969; }","duration":"519.920738ms","start":"2026-03-02T21:19:03.037412Z","end":"2026-03-02T21:19:03.557333Z","steps":["trace[1959956784] 'read index received' (duration: 174.404074ms)","trace[1959956784] 'applied index is now lower than readState.Index' (duration: 345.515574ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:03.557461Z","caller":"traceutil/trace.go:171","msg":"trace[1410180787] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"615.525552ms","start":"2026-03-02T21:19:02.941886Z","end":"2026-03-02T21:19:03.557411Z","steps":["trace[1410180787] 'process raft request' (duration: 269.912775ms)","trace[1410180787] 'compare' (duration: 345.196757ms)"],"step_count":2} {"level":"warn","ts":"2026-03-02T21:19:03.557615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.209556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-02T21:19:03.557657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:02.941861Z","time spent":"615.719328ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:03.557682Z","caller":"traceutil/trace.go:171","msg":"trace[1910881200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:933; }","duration":"520.32124ms","start":"2026-03-02T21:19:03.037324Z","end":"2026-03-02T21:19:03.557645Z","steps":["trace[1910881200] 'agreement among raft nodes before linearized reading' (duration: 520.106033ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:03.557731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:03.037303Z","time spent":"520.418071ms","remote":"127.0.0.1:33268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-03-02T21:19:03.611741Z","caller":"traceutil/trace.go:171","msg":"trace[517251940] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"498.895278ms","start":"2026-03-02T21:19:03.112822Z","end":"2026-03-02T21:19:03.611717Z","steps":["trace[517251940] 'process raft request' (duration: 498.728174ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:03.611891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-02T21:19:03.112795Z","time spent":"499.029891ms","remote":"127.0.0.1:33102","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":779,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-02T21:19:03.74494Z","caller":"traceutil/trace.go:171","msg":"trace[738486763] transaction","detail":"{read_only:false; response_revision:935; number_of_response:1; }","duration":"149.720921ms","start":"2026-03-02T21:19:03.595191Z","end":"2026-03-02T21:19:03.744912Z","steps":["trace[738486763] 'process raft request' (duration: 136.518067ms)","trace[738486763] 'compare' (duration: 12.963358ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:03.745184Z","caller":"traceutil/trace.go:171","msg":"trace[570565491] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"149.569107ms","start":"2026-03-02T21:19:03.595576Z","end":"2026-03-02T21:19:03.745145Z","steps":["trace[570565491] 'process raft request' (duration: 149.28152ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:03.756618Z","caller":"traceutil/trace.go:171","msg":"trace[448024344] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"141.103574ms","start":"2026-03-02T21:19:03.615347Z","end":"2026-03-02T21:19:03.756451Z","steps":["trace[448024344] 'process raft request' (duration: 140.890008ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:03.756754Z","caller":"traceutil/trace.go:171","msg":"trace[678296176] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"137.115672ms","start":"2026-03-02T21:19:03.619386Z","end":"2026-03-02T21:19:03.756502Z","steps":["trace[678296176] 'process raft request' (duration: 137.010859ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:19:04.075774Z","caller":"traceutil/trace.go:171","msg":"trace[1928968838] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"115.395984ms","start":"2026-03-02T21:19:03.960338Z","end":"2026-03-02T21:19:04.075734Z","steps":["trace[1928968838] 'process raft request' (duration: 115.156587ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.326134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.506427ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-02T21:19:04.326241Z","caller":"traceutil/trace.go:171","msg":"trace[1097958446] linearizableReadLoop","detail":"{readStateIndex:977; appliedIndex:976; }","duration":"105.346649ms","start":"2026-03-02T21:19:04.220878Z","end":"2026-03-02T21:19:04.326224Z","steps":["trace[1097958446] 'read index received' (duration: 74.211µs)","trace[1097958446] 'applied index is now lower than readState.Index' (duration: 105.271488ms)"],"step_count":2} {"level":"info","ts":"2026-03-02T21:19:04.326453Z","caller":"traceutil/trace.go:171","msg":"trace[507984658] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"245.822367ms","start":"2026-03-02T21:19:04.080618Z","end":"2026-03-02T21:19:04.32644Z","steps":["trace[507984658] 'compare' (duration: 235.377153ms)"],"step_count":1} {"level":"warn","ts":"2026-03-02T21:19:04.326859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.952476ms","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-02T21:19:04.327006Z","caller":"traceutil/trace.go:171","msg":"trace[1025256067] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:940; }","duration":"106.13641ms","start":"2026-03-02T21:19:04.220839Z","end":"2026-03-02T21:19:04.326976Z","steps":["trace[1025256067] 'agreement among raft nodes before linearized reading' (duration: 105.719789ms)"],"step_count":1} {"level":"info","ts":"2026-03-02T21:21:55.379098Z","caller":"traceutil/trace.go:171","msg":"trace[617480134] transaction","detail":"{read_only:false; response_revision:1662; number_of_response:1; }","duration":"141.023243ms","start":"2026-03-02T21:21:55.238042Z","end":"2026-03-02T21:21:55.379065Z","steps":["trace[617480134] 'process raft request' (duration: 88.132933ms)","trace[617480134] 'compare' (duration: 52.409558ms)"],"step_count":2}