{"level":"warn","ts":"2026-06-23T18:39:06.579633Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:39:06.579729Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.219:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.219:2380","--initial-cluster=instance=https://199.19.213.219:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.219:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.219: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-06-23T18:39:06.57979Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-23T18:39:06.579803Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-06-23T18:39:06.57982Z","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-06-23T18:39:06.580542Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"]} {"level":"info","ts":"2026-06-23T18:39:06.580614Z","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.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.219: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-06-23T18:39:06.59047Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.206302ms"} {"level":"info","ts":"2026-06-23T18:39:06.597323Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7e142186203fa167","cluster-id":"e7586aea7e662ee6"} {"level":"info","ts":"2026-06-23T18:39:06.597419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=()"} {"level":"info","ts":"2026-06-23T18:39:06.597446Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 0"} {"level":"info","ts":"2026-06-23T18:39:06.597465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7e142186203fa167 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-23T18:39:06.597489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became follower at term 1"} {"level":"info","ts":"2026-06-23T18:39:06.597542Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"warn","ts":"2026-06-23T18:39:06.609412Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-23T18:39:06.610688Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-23T18:39:06.612511Z","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-06-23T18:39:06.614709Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7e142186203fa167","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-23T18:39:06.615178Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7e142186203fa167","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-23T18:39:06.615663Z","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-06-23T18:39:06.61583Z","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-06-23T18:39:06.61585Z","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-06-23T18:39:06.618053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 switched to configuration voters=(9084923208263508327)"} {"level":"info","ts":"2026-06-23T18:39:06.618232Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","added-peer-id":"7e142186203fa167","added-peer-peer-urls":["https://199.19.213.219:2380"]} {"level":"info","ts":"2026-06-23T18:39:06.620805Z","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-06-23T18:39:06.62095Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-06-23T18:39:06.620976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.219:2380"} {"level":"info","ts":"2026-06-23T18:39:06.621243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7e142186203fa167","initial-advertise-peer-urls":["https://199.19.213.219:2380"],"listen-peer-urls":["https://199.19.213.219:2380"],"advertise-client-urls":["https://199.19.213.219:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.219:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-23T18:39:06.621282Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-23T18:39:07.30936Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 is starting a new election at term 1"} {"level":"info","ts":"2026-06-23T18:39:07.309414Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-23T18:39:07.309434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgPreVoteResp from 7e142186203fa167 at term 1"} {"level":"info","ts":"2026-06-23T18:39:07.309452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became candidate at term 2"} {"level":"info","ts":"2026-06-23T18:39:07.309468Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 received MsgVoteResp from 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-06-23T18:39:07.3095Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7e142186203fa167 became leader at term 2"} {"level":"info","ts":"2026-06-23T18:39:07.309514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7e142186203fa167 elected leader 7e142186203fa167 at term 2"} {"level":"info","ts":"2026-06-23T18:39:07.375758Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:39:07.376445Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:39:07.37644Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7e142186203fa167","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.219:2379]}","request-path":"/0/members/7e142186203fa167/attributes","cluster-id":"e7586aea7e662ee6","publish-timeout":"7s"} {"level":"info","ts":"2026-06-23T18:39:07.376544Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-23T18:39:07.378745Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-23T18:39:07.385367Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e7586aea7e662ee6","local-member-id":"7e142186203fa167","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:39:07.385493Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:39:07.38586Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-23T18:39:07.386139Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-23T18:39:07.386193Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-23T18:39:07.386516Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.219:2379"} {"level":"info","ts":"2026-06-23T18:39:09.730691Z","caller":"traceutil/trace.go:171","msg":"trace[1060087008] linearizableReadLoop","detail":"{readStateIndex:80; appliedIndex:78; }","duration":"155.976534ms","start":"2026-06-23T18:39:09.574691Z","end":"2026-06-23T18:39:09.730668Z","steps":["trace[1060087008] 'read index received' (duration: 72.698863ms)","trace[1060087008] 'applied index is now lower than readState.Index' (duration: 83.276671ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:09.730824Z","caller":"traceutil/trace.go:171","msg":"trace[534913669] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"156.519637ms","start":"2026-06-23T18:39:09.574294Z","end":"2026-06-23T18:39:09.730814Z","steps":["trace[534913669] 'process raft request' (duration: 156.334456ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:09.730818Z","caller":"traceutil/trace.go:171","msg":"trace[1801080734] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"157.06831ms","start":"2026-06-23T18:39:09.573718Z","end":"2026-06-23T18:39:09.730787Z","steps":["trace[1801080734] 'process raft request' (duration: 73.662968ms)","trace[1801080734] 'compare' (duration: 83.10445ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:09.731142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.265769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-23T18:39:09.731229Z","caller":"traceutil/trace.go:171","msg":"trace[235057261] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"153.386229ms","start":"2026-06-23T18:39:09.577827Z","end":"2026-06-23T18:39:09.731213Z","steps":["trace[235057261] 'agreement among raft nodes before linearized reading' (duration: 153.185738ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:09.731196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.446717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-23T18:39:09.732291Z","caller":"traceutil/trace.go:171","msg":"trace[1109348184] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:75; }","duration":"157.604003ms","start":"2026-06-23T18:39:09.574653Z","end":"2026-06-23T18:39:09.732257Z","steps":["trace[1109348184] 'agreement among raft nodes before linearized reading' (duration: 156.422937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:09.952642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.266307ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-23T18:39:09.952809Z","caller":"traceutil/trace.go:171","msg":"trace[1367506180] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"161.375693ms","start":"2026-06-23T18:39:09.791416Z","end":"2026-06-23T18:39:09.952792Z","steps":["trace[1367506180] 'process raft request' (duration: 161.333353ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:09.952823Z","caller":"traceutil/trace.go:171","msg":"trace[692372782] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"216.75418ms","start":"2026-06-23T18:39:09.736028Z","end":"2026-06-23T18:39:09.952783Z","steps":["trace[692372782] 'process raft request' (duration: 111.94153ms)","trace[692372782] 'compare' (duration: 104.124906ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:09.953014Z","caller":"traceutil/trace.go:171","msg":"trace[579099070] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:80; }","duration":"216.70739ms","start":"2026-06-23T18:39:09.73622Z","end":"2026-06-23T18:39:09.952927Z","steps":["trace[579099070] 'read index received' (duration: 51.654236ms)","trace[579099070] 'applied index is now lower than readState.Index' (duration: 165.049174ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:09.953189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.941991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-23T18:39:09.953295Z","caller":"traceutil/trace.go:171","msg":"trace[2108538827] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:78; }","duration":"217.083282ms","start":"2026-06-23T18:39:09.736187Z","end":"2026-06-23T18:39:09.953271Z","steps":["trace[2108538827] 'agreement among raft nodes before linearized reading' (duration: 216.864811ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:10.292413Z","caller":"traceutil/trace.go:171","msg":"trace[1594278896] linearizableReadLoop","detail":"{readStateIndex:87; appliedIndex:86; }","duration":"151.024601ms","start":"2026-06-23T18:39:10.141363Z","end":"2026-06-23T18:39:10.292387Z","steps":["trace[1594278896] 'read index received' (duration: 51.706884ms)","trace[1594278896] 'applied index is now lower than readState.Index' (duration: 99.316047ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:10.292777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.359833ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-23T18:39:10.292922Z","caller":"traceutil/trace.go:171","msg":"trace[958433305] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:82; }","duration":"151.581504ms","start":"2026-06-23T18:39:10.141318Z","end":"2026-06-23T18:39:10.2929Z","steps":["trace[958433305] 'agreement among raft nodes before linearized reading' (duration: 151.317772ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:10.29309Z","caller":"traceutil/trace.go:171","msg":"trace[336706322] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"181.978861ms","start":"2026-06-23T18:39:10.110457Z","end":"2026-06-23T18:39:10.292435Z","steps":["trace[336706322] 'process raft request' (duration: 82.659415ms)","trace[336706322] 'compare' (duration: 99.148655ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:11.377451Z","caller":"traceutil/trace.go:171","msg":"trace[314232174] transaction","detail":"{read_only:false; response_revision:140; number_of_response:1; }","duration":"130.725365ms","start":"2026-06-23T18:39:11.246701Z","end":"2026-06-23T18:39:11.377426Z","steps":["trace[314232174] 'process raft request' (duration: 31.742133ms)","trace[314232174] 'compare' (duration: 98.77325ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:11.379814Z","caller":"traceutil/trace.go:171","msg":"trace[965558067] transaction","detail":"{read_only:false; response_revision:141; number_of_response:1; }","duration":"131.766371ms","start":"2026-06-23T18:39:11.248028Z","end":"2026-06-23T18:39:11.379795Z","steps":["trace[965558067] 'process raft request' (duration: 131.274768ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:13.102225Z","caller":"traceutil/trace.go:171","msg":"trace[1083689562] transaction","detail":"{read_only:false; response_revision:250; number_of_response:1; }","duration":"100.877325ms","start":"2026-06-23T18:39:13.001289Z","end":"2026-06-23T18:39:13.102166Z","steps":["trace[1083689562] 'process raft request' (duration: 91.963507ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:13.745727Z","caller":"traceutil/trace.go:171","msg":"trace[306048561] transaction","detail":"{read_only:false; response_revision:256; number_of_response:1; }","duration":"113.0582ms","start":"2026-06-23T18:39:13.632649Z","end":"2026-06-23T18:39:13.745707Z","steps":["trace[306048561] 'process raft request' (duration: 112.91123ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:15.674328Z","caller":"traceutil/trace.go:171","msg":"trace[978882481] transaction","detail":"{read_only:false; response_revision:299; number_of_response:1; }","duration":"130.791718ms","start":"2026-06-23T18:39:15.543508Z","end":"2026-06-23T18:39:15.674299Z","steps":["trace[978882481] 'process raft request' (duration: 130.452797ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:16.028711Z","caller":"traceutil/trace.go:171","msg":"trace[121418479] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"280.690648ms","start":"2026-06-23T18:39:15.747997Z","end":"2026-06-23T18:39:16.028687Z","steps":["trace[121418479] 'process raft request' (duration: 280.639388ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:16.028766Z","caller":"traceutil/trace.go:171","msg":"trace[286803511] transaction","detail":"{read_only:false; response_revision:304; number_of_response:1; }","duration":"281.492693ms","start":"2026-06-23T18:39:15.747243Z","end":"2026-06-23T18:39:16.028736Z","steps":["trace[286803511] 'process raft request' (duration: 217.601232ms)","trace[286803511] 'compare' (duration: 63.624319ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:16.349591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.580853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:39:16.349713Z","caller":"traceutil/trace.go:171","msg":"trace[1491822227] transaction","detail":"{read_only:false; response_revision:309; number_of_response:1; }","duration":"229.070096ms","start":"2026-06-23T18:39:16.12062Z","end":"2026-06-23T18:39:16.34969Z","steps":["trace[1491822227] 'process raft request' (duration: 19.317973ms)","trace[1491822227] 'compare' (duration: 209.429712ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:30.168017Z","caller":"traceutil/trace.go:171","msg":"trace[2049656337] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"114.788774ms","start":"2026-06-23T18:39:30.05318Z","end":"2026-06-23T18:39:30.167969Z","steps":["trace[2049656337] 'process raft request' (duration: 114.581904ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:32.855087Z","caller":"traceutil/trace.go:171","msg":"trace[41498736] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"145.321657ms","start":"2026-06-23T18:39:32.709742Z","end":"2026-06-23T18:39:32.855064Z","steps":["trace[41498736] 'process raft request' (duration: 91.617619ms)","trace[41498736] 'compare' (duration: 53.266177ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:35.369163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214458,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:39:35.433048Z","caller":"traceutil/trace.go:171","msg":"trace[836937455] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"564.907805ms","start":"2026-06-23T18:39:34.8681Z","end":"2026-06-23T18:39:35.433008Z","steps":["trace[836937455] 'read index received' (duration: 564.698605ms)","trace[836937455] 'applied index is now lower than readState.Index' (duration: 208.21µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:35.433092Z","caller":"traceutil/trace.go:171","msg":"trace[1652885472] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"576.398644ms","start":"2026-06-23T18:39:34.856667Z","end":"2026-06-23T18:39:35.433066Z","steps":["trace[1652885472] 'process raft request' (duration: 576.216885ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:35.433193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.081862ms","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-06-23T18:39:35.433258Z","caller":"traceutil/trace.go:171","msg":"trace[1069738138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:469; }","duration":"565.172736ms","start":"2026-06-23T18:39:34.868064Z","end":"2026-06-23T18:39:35.433236Z","steps":["trace[1069738138] 'agreement among raft nodes before linearized reading' (duration: 565.0421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:35.4333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:34.868027Z","time spent":"565.265351ms","remote":"127.0.0.1:57724","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-06-23T18:39:35.433701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:34.856636Z","time spent":"576.53013ms","remote":"127.0.0.1:57724","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-06-23T18:39:35.433208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.282368ms","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-06-23T18:39:35.43387Z","caller":"traceutil/trace.go:171","msg":"trace[1924945292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:469; }","duration":"197.967468ms","start":"2026-06-23T18:39:35.235867Z","end":"2026-06-23T18:39:35.433834Z","steps":["trace[1924945292] 'agreement among raft nodes before linearized reading' (duration: 197.285627ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:36.098098Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214462,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:39:36.481482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.918959ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:39:36.481636Z","caller":"traceutil/trace.go:171","msg":"trace[686843884] linearizableReadLoop","detail":"{readStateIndex:487; appliedIndex:485; }","duration":"883.705365ms","start":"2026-06-23T18:39:35.597917Z","end":"2026-06-23T18:39:36.481622Z","steps":["trace[686843884] 'read index received' (duration: 666.43864ms)","trace[686843884] 'applied index is now lower than readState.Index' (duration: 217.265775ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:36.481739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.34733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:39:36.481723Z","caller":"traceutil/trace.go:171","msg":"trace[1284212456] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"1.044543364s","start":"2026-06-23T18:39:35.437151Z","end":"2026-06-23T18:39:36.481694Z","steps":["trace[1284212456] 'process raft request' (duration: 827.338282ms)","trace[1284212456] 'compare' (duration: 216.812684ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:36.481765Z","caller":"traceutil/trace.go:171","msg":"trace[352905758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"357.394022ms","start":"2026-06-23T18:39:36.124361Z","end":"2026-06-23T18:39:36.481755Z","steps":["trace[352905758] 'agreement among raft nodes before linearized reading' (duration: 357.340629ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:36.481791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:36.124346Z","time spent":"357.438834ms","remote":"127.0.0.1:57452","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-06-23T18:39:36.481791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"883.858461ms","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-06-23T18:39:36.481819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:35.43713Z","time spent":"1.044641518s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:36.481845Z","caller":"traceutil/trace.go:171","msg":"trace[1163986322] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:471; }","duration":"883.947135ms","start":"2026-06-23T18:39:35.597883Z","end":"2026-06-23T18:39:36.48183Z","steps":["trace[1163986322] 'agreement among raft nodes before linearized reading' (duration: 883.826029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:36.481832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.195707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-23T18:39:36.481879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:35.597865Z","time spent":"884.007338ms","remote":"127.0.0.1:57724","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-06-23T18:39:36.481898Z","caller":"traceutil/trace.go:171","msg":"trace[796120791] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:471; }","duration":"287.305722ms","start":"2026-06-23T18:39:36.194575Z","end":"2026-06-23T18:39:36.481881Z","steps":["trace[796120791] 'agreement among raft nodes before linearized reading' (duration: 287.188417ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:36.48191Z","caller":"traceutil/trace.go:171","msg":"trace[987542309] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"1.04446044s","start":"2026-06-23T18:39:35.437433Z","end":"2026-06-23T18:39:36.481894Z","steps":["trace[987542309] 'process raft request' (duration: 1.044149236s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:36.481985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:35.437412Z","time spent":"1.044530633s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:38.792759Z","caller":"traceutil/trace.go:171","msg":"trace[680880873] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"303.686622ms","start":"2026-06-23T18:39:38.489051Z","end":"2026-06-23T18:39:38.792737Z","steps":["trace[680880873] 'process raft request' (duration: 303.511265ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:38.792898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:38.489032Z","time spent":"303.795167ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:38.928784Z","caller":"traceutil/trace.go:171","msg":"trace[37537084] linearizableReadLoop","detail":"{readStateIndex:492; appliedIndex:490; }","duration":"436.276086ms","start":"2026-06-23T18:39:38.492484Z","end":"2026-06-23T18:39:38.92876Z","steps":["trace[37537084] 'read index received' (duration: 300.143588ms)","trace[37537084] 'applied index is now lower than readState.Index' (duration: 136.131307ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:38.928842Z","caller":"traceutil/trace.go:171","msg":"trace[252004119] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"439.636083ms","start":"2026-06-23T18:39:38.489179Z","end":"2026-06-23T18:39:38.928815Z","steps":["trace[252004119] 'process raft request' (duration: 401.571364ms)","trace[252004119] 'compare' (duration: 37.885021ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:38.928944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.437362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-06-23T18:39:38.928976Z","caller":"traceutil/trace.go:171","msg":"trace[1475924610] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"436.504796ms","start":"2026-06-23T18:39:38.492461Z","end":"2026-06-23T18:39:38.928966Z","steps":["trace[1475924610] 'agreement among raft nodes before linearized reading' (duration: 436.428052ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:38.928973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:38.489162Z","time spent":"439.737377ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:39:38.929028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:38.492453Z","time spent":"436.555587ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T18:39:38.929203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.223423ms","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-06-23T18:39:38.929242Z","caller":"traceutil/trace.go:171","msg":"trace[325597613] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"436.289856ms","start":"2026-06-23T18:39:38.492944Z","end":"2026-06-23T18:39:38.929234Z","steps":["trace[325597613] 'agreement among raft nodes before linearized reading' (duration: 435.969102ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:38.929372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:38.492931Z","time spent":"436.433852ms","remote":"127.0.0.1:57724","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-06-23T18:39:39.411067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.901317ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:39:39.411162Z","caller":"traceutil/trace.go:171","msg":"trace[2087493287] linearizableReadLoop","detail":"{readStateIndex:494; appliedIndex:493; }","duration":"287.75523ms","start":"2026-06-23T18:39:39.123391Z","end":"2026-06-23T18:39:39.411147Z","steps":["trace[2087493287] 'read index received' (duration: 29.561µs)","trace[2087493287] 'applied index is now lower than readState.Index' (duration: 287.724599ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:39.411237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.846884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:39:39.411269Z","caller":"traceutil/trace.go:171","msg":"trace[1844842203] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:477; }","duration":"287.901647ms","start":"2026-06-23T18:39:39.123359Z","end":"2026-06-23T18:39:39.411261Z","steps":["trace[1844842203] 'agreement among raft nodes before linearized reading' (duration: 287.832544ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:39.411301Z","caller":"traceutil/trace.go:171","msg":"trace[1180225905] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"478.283498ms","start":"2026-06-23T18:39:38.932985Z","end":"2026-06-23T18:39:39.411268Z","steps":["trace[1180225905] 'process raft request' (duration: 99.102559ms)","trace[1180225905] 'compare' (duration: 378.795412ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:39.41142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:38.932974Z","time spent":"478.387413ms","remote":"127.0.0.1:57724","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-06-23T18:39:41.872447Z","caller":"traceutil/trace.go:171","msg":"trace[1930464817] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"452.80046ms","start":"2026-06-23T18:39:41.419625Z","end":"2026-06-23T18:39:41.872426Z","steps":["trace[1930464817] 'process raft request' (duration: 452.673314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:41.872597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:41.41961Z","time spent":"452.912195ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:39:42.24428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.027797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:39:42.244366Z","caller":"traceutil/trace.go:171","msg":"trace[675230265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"119.151253ms","start":"2026-06-23T18:39:42.125191Z","end":"2026-06-23T18:39:42.244342Z","steps":["trace[675230265] 'range keys from in-memory index tree' (duration: 118.929533ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:48.033976Z","caller":"traceutil/trace.go:171","msg":"trace[470063279] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"281.087137ms","start":"2026-06-23T18:39:47.752862Z","end":"2026-06-23T18:39:48.033949Z","steps":["trace[470063279] 'process raft request' (duration: 274.094226ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:48.034053Z","caller":"traceutil/trace.go:171","msg":"trace[2107296305] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"280.075538ms","start":"2026-06-23T18:39:47.753953Z","end":"2026-06-23T18:39:48.034029Z","steps":["trace[2107296305] 'read index received' (duration: 273.034205ms)","trace[2107296305] 'applied index is now lower than readState.Index' (duration: 7.039983ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:48.034068Z","caller":"traceutil/trace.go:171","msg":"trace[829071132] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"155.88962ms","start":"2026-06-23T18:39:47.878151Z","end":"2026-06-23T18:39:48.034041Z","steps":["trace[829071132] 'process raft request' (duration: 155.738044ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.034519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.537106ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:65436"} {"level":"info","ts":"2026-06-23T18:39:48.034562Z","caller":"traceutil/trace.go:171","msg":"trace[1633169483] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/clienttrafficpolicies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:620; }","duration":"280.62745ms","start":"2026-06-23T18:39:47.753924Z","end":"2026-06-23T18:39:48.034552Z","steps":["trace[1633169483] 'agreement among raft nodes before linearized reading' (duration: 280.324758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.695124Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214726,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:39:48.936143Z","caller":"traceutil/trace.go:171","msg":"trace[538372801] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"808.676829ms","start":"2026-06-23T18:39:48.127447Z","end":"2026-06-23T18:39:48.936124Z","steps":["trace[538372801] 'process raft request' (duration: 803.991647ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.936314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:48.127429Z","time spent":"808.784263ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":208470,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:48.936613Z","caller":"traceutil/trace.go:171","msg":"trace[1878852500] linearizableReadLoop","detail":"{readStateIndex:649; appliedIndex:646; }","duration":"742.056593ms","start":"2026-06-23T18:39:48.194522Z","end":"2026-06-23T18:39:48.936579Z","steps":["trace[1878852500] 'read index received' (duration: 736.921423ms)","trace[1878852500] 'applied index is now lower than readState.Index' (duration: 5.13408ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:39:48.936727Z","caller":"traceutil/trace.go:171","msg":"trace[221225696] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"459.182043ms","start":"2026-06-23T18:39:48.477538Z","end":"2026-06-23T18:39:48.93672Z","steps":["trace[221225696] 'process raft request' (duration: 458.929334ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.936825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:48.477512Z","time spent":"459.256786ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:39:48.936936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.19127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-82lw5\" ","response":"range_response_count:1 size:4888"} {"level":"info","ts":"2026-06-23T18:39:48.936968Z","caller":"traceutil/trace.go:171","msg":"trace[1151603838] range","detail":"{range_begin:/registry/pods/kube-system/cilium-operator-869df985b8-82lw5; range_end:; response_count:1; response_revision:629; }","duration":"521.278134ms","start":"2026-06-23T18:39:48.415684Z","end":"2026-06-23T18:39:48.936962Z","steps":["trace[1151603838] 'agreement among raft nodes before linearized reading' (duration: 521.162639ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:48.936662Z","caller":"traceutil/trace.go:171","msg":"trace[1055602497] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"661.657712ms","start":"2026-06-23T18:39:48.274975Z","end":"2026-06-23T18:39:48.936633Z","steps":["trace[1055602497] 'process raft request' (duration: 661.0962ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.936984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:48.415668Z","time spent":"521.312404ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":4911,"request content":"key:\"/registry/pods/kube-system/cilium-operator-869df985b8-82lw5\" "} {"level":"warn","ts":"2026-06-23T18:39:48.937012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:48.274952Z","time spent":"662.039777ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-23T18:39:48.937079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.129693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:39:48.937098Z","caller":"traceutil/trace.go:171","msg":"trace[80367404] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:629; }","duration":"263.189976ms","start":"2026-06-23T18:39:48.673903Z","end":"2026-06-23T18:39:48.937093Z","steps":["trace[80367404] 'agreement among raft nodes before linearized reading' (duration: 263.162335ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.937194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"742.655116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:15688"} {"level":"info","ts":"2026-06-23T18:39:48.937296Z","caller":"traceutil/trace.go:171","msg":"trace[2068390249] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:629; }","duration":"742.76644ms","start":"2026-06-23T18:39:48.194512Z","end":"2026-06-23T18:39:48.937279Z","steps":["trace[2068390249] 'agreement among raft nodes before linearized reading' (duration: 742.310512ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:48.937362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:48.194452Z","time spent":"742.897686ms","remote":"127.0.0.1:57508","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":15711,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/httproutefilters.gateway.envoyproxy.io\" "} {"level":"info","ts":"2026-06-23T18:39:49.44962Z","caller":"traceutil/trace.go:171","msg":"trace[1236834415] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:664; }","duration":"160.884ms","start":"2026-06-23T18:39:49.288713Z","end":"2026-06-23T18:39:49.449597Z","steps":["trace[1236834415] 'read index received' (duration: 160.630211ms)","trace[1236834415] 'applied index is now lower than readState.Index' (duration: 252.869µs)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:49.450021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.267935ms","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-06-23T18:39:49.45006Z","caller":"traceutil/trace.go:171","msg":"trace[1238749233] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:645; }","duration":"161.37113ms","start":"2026-06-23T18:39:49.28868Z","end":"2026-06-23T18:39:49.450051Z","steps":["trace[1238749233] 'agreement among raft nodes before linearized reading' (duration: 161.027787ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:49.450181Z","caller":"traceutil/trace.go:171","msg":"trace[1525812682] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"170.094684ms","start":"2026-06-23T18:39:49.280047Z","end":"2026-06-23T18:39:49.450141Z","steps":["trace[1525812682] 'process raft request' (duration: 169.397177ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:55.743104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.170308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-hwt4l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-06-23T18:39:55.743168Z","caller":"traceutil/trace.go:171","msg":"trace[412129710] range","detail":"{range_begin:/registry/pods/kube-system/cilium-hwt4l; range_end:; response_count:1; response_revision:697; }","duration":"312.304622ms","start":"2026-06-23T18:39:55.43085Z","end":"2026-06-23T18:39:55.743154Z","steps":["trace[412129710] 'range keys from in-memory index tree' (duration: 312.000371ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:55.743195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:55.43082Z","time spent":"312.368604ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-hwt4l\" "} {"level":"warn","ts":"2026-06-23T18:39:55.743429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.828331ms","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-06-23T18:39:55.743458Z","caller":"traceutil/trace.go:171","msg":"trace[2060621007] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:697; }","duration":"244.909004ms","start":"2026-06-23T18:39:55.498542Z","end":"2026-06-23T18:39:55.743451Z","steps":["trace[2060621007] 'range keys from in-memory index tree' (duration: 244.730717ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:55.893775Z","caller":"traceutil/trace.go:171","msg":"trace[1332885609] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"127.644262ms","start":"2026-06-23T18:39:55.7661Z","end":"2026-06-23T18:39:55.893745Z","steps":["trace[1332885609] 'process raft request' (duration: 127.57621ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:55.893798Z","caller":"traceutil/trace.go:171","msg":"trace[1050414243] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"131.166228ms","start":"2026-06-23T18:39:55.762571Z","end":"2026-06-23T18:39:55.893738Z","steps":["trace[1050414243] 'process raft request' (duration: 93.199891ms)","trace[1050414243] 'compare' (duration: 37.793271ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:56.872423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.218176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-23T18:39:56.873723Z","caller":"traceutil/trace.go:171","msg":"trace[1115730504] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"785.554463ms","start":"2026-06-23T18:39:56.088128Z","end":"2026-06-23T18:39:56.873682Z","steps":["trace[1115730504] 'range keys from in-memory index tree' (duration: 784.006388ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:56.872672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.719577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:39:56.873818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:56.088109Z","time spent":"785.681278ms","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-23T18:39:56.873891Z","caller":"traceutil/trace.go:171","msg":"trace[124678716] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:700; }","duration":"748.923849ms","start":"2026-06-23T18:39:56.124893Z","end":"2026-06-23T18:39:56.873816Z","steps":["trace[124678716] 'range keys from in-memory index tree' (duration: 747.659584ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:56.87408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:56.124872Z","time spent":"749.141387ms","remote":"127.0.0.1:57452","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-06-23T18:39:57.538641Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:39:58.039147Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:39:58.540039Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157214973,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-23T18:39:58.849462Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.87604644s","expected-duration":"1s"} {"level":"info","ts":"2026-06-23T18:39:58.857681Z","caller":"traceutil/trace.go:171","msg":"trace[749372160] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"1.884320936s","start":"2026-06-23T18:39:56.973312Z","end":"2026-06-23T18:39:58.857633Z","steps":["trace[749372160] 'process raft request' (duration: 1.87635082s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:58.85787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:56.973292Z","time spent":"1.884456791s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:39:58.878412Z","caller":"traceutil/trace.go:171","msg":"trace[1685549649] linearizableReadLoop","detail":"{readStateIndex:724; appliedIndex:722; }","duration":"1.840577178s","start":"2026-06-23T18:39:57.03781Z","end":"2026-06-23T18:39:58.878387Z","steps":["trace[1685549649] 'read index received' (duration: 1.811841696s)","trace[1685549649] 'applied index is now lower than readState.Index' (duration: 28.734062ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:39:58.878659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.779571347s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-23T18:39:58.878659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.75330312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:39:58.878688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109712073s","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-06-23T18:39:58.878698Z","caller":"traceutil/trace.go:171","msg":"trace[1691493097] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:702; }","duration":"1.77965655s","start":"2026-06-23T18:39:57.09903Z","end":"2026-06-23T18:39:58.878687Z","steps":["trace[1691493097] 'agreement among raft nodes before linearized reading' (duration: 1.779494115s)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:58.878715Z","caller":"traceutil/trace.go:171","msg":"trace[1048792252] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:702; }","duration":"1.753367152s","start":"2026-06-23T18:39:57.125333Z","end":"2026-06-23T18:39:58.8787Z","steps":["trace[1048792252] 'agreement among raft nodes before linearized reading' (duration: 1.753278829s)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:39:58.87874Z","caller":"traceutil/trace.go:171","msg":"trace[1078167652] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:702; }","duration":"1.109805357s","start":"2026-06-23T18:39:57.768922Z","end":"2026-06-23T18:39:58.878728Z","steps":["trace[1078167652] 'agreement among raft nodes before linearized reading' (duration: 1.109658702s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:58.878749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:57.125239Z","time spent":"1.753502566s","remote":"127.0.0.1:57452","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-06-23T18:39:58.878746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:57.099009Z","time spent":"1.779728553s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-23T18:39:58.878774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:57.768895Z","time spent":"1.109873469s","remote":"127.0.0.1:57724","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-06-23T18:39:58.879047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.8412424s","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-06-23T18:39:58.879081Z","caller":"traceutil/trace.go:171","msg":"trace[474091024] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:702; }","duration":"1.841310063s","start":"2026-06-23T18:39:57.037762Z","end":"2026-06-23T18:39:58.879072Z","steps":["trace[474091024] 'agreement among raft nodes before linearized reading' (duration: 1.840736503s)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:39:58.879102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:39:57.037699Z","time spent":"1.841398806s","remote":"127.0.0.1:57724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-23T18:40:00.018034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.159807ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-06-23T18:40:00.018163Z","caller":"traceutil/trace.go:171","msg":"trace[335023043] transaction","detail":"{read_only:false; response_revision:710; number_of_response:1; }","duration":"236.475546ms","start":"2026-06-23T18:39:59.781664Z","end":"2026-06-23T18:40:00.018139Z","steps":["trace[335023043] 'process raft request' (duration: 19.106282ms)","trace[335023043] 'compare' (duration: 217.031182ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:01.598937Z","caller":"traceutil/trace.go:171","msg":"trace[1909672882] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"128.963243ms","start":"2026-06-23T18:40:01.469953Z","end":"2026-06-23T18:40:01.598916Z","steps":["trace[1909672882] 'process raft request' (duration: 128.827338ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:03.630763Z","caller":"traceutil/trace.go:171","msg":"trace[26907435] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"135.215541ms","start":"2026-06-23T18:40:03.495524Z","end":"2026-06-23T18:40:03.63074Z","steps":["trace[26907435] 'process raft request' (duration: 135.055995ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:11.892353Z","caller":"traceutil/trace.go:171","msg":"trace[1619086476] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"308.102192ms","start":"2026-06-23T18:40:11.584212Z","end":"2026-06-23T18:40:11.892314Z","steps":["trace[1619086476] 'process raft request' (duration: 307.70062ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:11.892849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:11.584196Z","time spent":"308.566775ms","remote":"127.0.0.1:57902","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:15.252348Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11630439341157215195,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-23T18:40:15.470518Z","caller":"traceutil/trace.go:171","msg":"trace[264973448] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"923.393242ms","start":"2026-06-23T18:40:14.547106Z","end":"2026-06-23T18:40:15.4705Z","steps":["trace[264973448] 'process raft request' (duration: 923.246918ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.47069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.547087Z","time spent":"923.496295ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4028,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-23T18:40:15.728601Z","caller":"traceutil/trace.go:171","msg":"trace[1980274999] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:841; }","duration":"977.334153ms","start":"2026-06-23T18:40:14.751238Z","end":"2026-06-23T18:40:15.728573Z","steps":["trace[1980274999] 'read index received' (duration: 719.181477ms)","trace[1980274999] 'applied index is now lower than readState.Index' (duration: 258.151046ms)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:15.728688Z","caller":"traceutil/trace.go:171","msg":"trace[226347345] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"532.286191ms","start":"2026-06-23T18:40:15.196376Z","end":"2026-06-23T18:40:15.728662Z","steps":["trace[226347345] 'process raft request' (duration: 532.043314ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.728831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:15.196351Z","time spent":"532.410735ms","remote":"127.0.0.1:57624","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5052,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-23T18:40:15.728876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"977.598871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-23T18:40:15.728912Z","caller":"traceutil/trace.go:171","msg":"trace[1477821968] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:816; }","duration":"977.703834ms","start":"2026-06-23T18:40:14.751198Z","end":"2026-06-23T18:40:15.728902Z","steps":["trace[1477821968] 'agreement among raft nodes before linearized reading' (duration: 977.5862ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.728958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.75118Z","time spent":"977.767075ms","remote":"127.0.0.1:57638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":12,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-06-23T18:40:15.729009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.078067ms","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-06-23T18:40:15.729034Z","caller":"traceutil/trace.go:171","msg":"trace[1809729543] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:816; }","duration":"800.138988ms","start":"2026-06-23T18:40:14.928888Z","end":"2026-06-23T18:40:15.729027Z","steps":["trace[1809729543] 'agreement among raft nodes before linearized reading' (duration: 800.042785ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.729052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.928867Z","time spent":"800.18135ms","remote":"127.0.0.1:57724","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-06-23T18:40:15.729254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.065212ms","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-06-23T18:40:15.729321Z","caller":"traceutil/trace.go:171","msg":"trace[1647557757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:816; }","duration":"748.136404ms","start":"2026-06-23T18:40:14.981168Z","end":"2026-06-23T18:40:15.729304Z","steps":["trace[1647557757] 'agreement among raft nodes before linearized reading' (duration: 747.940089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.729315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.987658ms","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":"warn","ts":"2026-06-23T18:40:15.729355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"750.098261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-23T18:40:15.729377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.9811Z","time spent":"748.250458ms","remote":"127.0.0.1:57724","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-06-23T18:40:15.729383Z","caller":"traceutil/trace.go:171","msg":"trace[1747402597] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:816; }","duration":"750.183093ms","start":"2026-06-23T18:40:14.979193Z","end":"2026-06-23T18:40:15.729376Z","steps":["trace[1747402597] 'agreement among raft nodes before linearized reading' (duration: 750.108741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.729413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.97917Z","time spent":"750.234535ms","remote":"127.0.0.1:57724","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-06-23T18:40:15.72938Z","caller":"traceutil/trace.go:171","msg":"trace[1573786633] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:816; }","duration":"760.119661ms","start":"2026-06-23T18:40:14.969243Z","end":"2026-06-23T18:40:15.729362Z","steps":["trace[1573786633] 'agreement among raft nodes before linearized reading' (duration: 759.964626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.72945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.969213Z","time spent":"760.222344ms","remote":"127.0.0.1:57724","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-06-23T18:40:15.729573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"745.418736ms","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-06-23T18:40:15.729611Z","caller":"traceutil/trace.go:171","msg":"trace[1324429983] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:816; }","duration":"745.513089ms","start":"2026-06-23T18:40:14.984088Z","end":"2026-06-23T18:40:15.729601Z","steps":["trace[1324429983] 'agreement among raft nodes before linearized reading' (duration: 745.363034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:15.729654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-23T18:40:14.984063Z","time spent":"745.583801ms","remote":"127.0.0.1:57724","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-06-23T18:40:29.935962Z","caller":"traceutil/trace.go:171","msg":"trace[2005299835] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"108.577203ms","start":"2026-06-23T18:40:29.827365Z","end":"2026-06-23T18:40:29.935943Z","steps":["trace[2005299835] 'process raft request' (duration: 108.433119ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:29.947983Z","caller":"traceutil/trace.go:171","msg":"trace[773497758] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"120.208642ms","start":"2026-06-23T18:40:29.827754Z","end":"2026-06-23T18:40:29.947963Z","steps":["trace[773497758] 'process raft request' (duration: 119.870534ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:29.948002Z","caller":"traceutil/trace.go:171","msg":"trace[1122788496] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"120.134281ms","start":"2026-06-23T18:40:29.82784Z","end":"2026-06-23T18:40:29.947974Z","steps":["trace[1122788496] 'process raft request' (duration: 119.992287ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:29.948308Z","caller":"traceutil/trace.go:171","msg":"trace[1548861138] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"120.277984ms","start":"2026-06-23T18:40:29.828004Z","end":"2026-06-23T18:40:29.948282Z","steps":["trace[1548861138] 'process raft request' (duration: 119.862914ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:29.948333Z","caller":"traceutil/trace.go:171","msg":"trace[891422031] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"119.958986ms","start":"2026-06-23T18:40:29.82835Z","end":"2026-06-23T18:40:29.948308Z","steps":["trace[891422031] 'process raft request' (duration: 119.853123ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:56.299489Z","caller":"traceutil/trace.go:171","msg":"trace[1084211103] linearizableReadLoop","detail":"{readStateIndex:1117; appliedIndex:1116; }","duration":"224.55649ms","start":"2026-06-23T18:40:56.07491Z","end":"2026-06-23T18:40:56.299466Z","steps":["trace[1084211103] 'read index received' (duration: 224.417197ms)","trace[1084211103] 'applied index is now lower than readState.Index' (duration: 138.513µs)"],"step_count":2} {"level":"info","ts":"2026-06-23T18:40:56.299558Z","caller":"traceutil/trace.go:171","msg":"trace[1185132359] transaction","detail":"{read_only:false; response_revision:1081; number_of_response:1; }","duration":"225.386706ms","start":"2026-06-23T18:40:56.074141Z","end":"2026-06-23T18:40:56.299527Z","steps":["trace[1185132359] 'process raft request' (duration: 225.137791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.29981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.887486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-23T18:40:56.299878Z","caller":"traceutil/trace.go:171","msg":"trace[646259947] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1081; }","duration":"225.008498ms","start":"2026-06-23T18:40:56.074854Z","end":"2026-06-23T18:40:56.299862Z","steps":["trace[646259947] 'agreement among raft nodes before linearized reading' (duration: 224.767164ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.299813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.909828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-23T18:40:56.299938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.602063ms","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-06-23T18:40:56.300003Z","caller":"traceutil/trace.go:171","msg":"trace[1760407504] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1081; }","duration":"219.712485ms","start":"2026-06-23T18:40:56.080275Z","end":"2026-06-23T18:40:56.299987Z","steps":["trace[1760407504] 'agreement among raft nodes before linearized reading' (duration: 219.535391ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:40:56.299986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.805361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-23T18:40:56.299908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.561402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-06-23T18:40:56.300083Z","caller":"traceutil/trace.go:171","msg":"trace[1180559265] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1081; }","duration":"175.942003ms","start":"2026-06-23T18:40:56.124118Z","end":"2026-06-23T18:40:56.30006Z","steps":["trace[1180559265] 'agreement among raft nodes before linearized reading' (duration: 175.735919ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:56.3Z","caller":"traceutil/trace.go:171","msg":"trace[302497390] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1081; }","duration":"221.112382ms","start":"2026-06-23T18:40:56.078858Z","end":"2026-06-23T18:40:56.29997Z","steps":["trace[302497390] 'agreement among raft nodes before linearized reading' (duration: 220.828277ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:40:56.30057Z","caller":"traceutil/trace.go:171","msg":"trace[335673798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1081; }","duration":"219.780026ms","start":"2026-06-23T18:40:56.080316Z","end":"2026-06-23T18:40:56.300096Z","steps":["trace[335673798] 'agreement among raft nodes before linearized reading' (duration: 219.444349ms)"],"step_count":1} {"level":"warn","ts":"2026-06-23T18:41:08.0572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.032166ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-06-23T18:41:08.057312Z","caller":"traceutil/trace.go:171","msg":"trace[1857778490] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"278.746289ms","start":"2026-06-23T18:41:07.778549Z","end":"2026-06-23T18:41:08.057295Z","steps":["trace[1857778490] 'read index received' (duration: 149.638142ms)","trace[1857778490] 'applied index is now lower than readState.Index' (duration: 129.106557ms)"],"step_count":2} {"level":"warn","ts":"2026-06-23T18:41:08.057385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.829731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-23T18:41:08.057418Z","caller":"traceutil/trace.go:171","msg":"trace[1593801072] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1120; }","duration":"278.904982ms","start":"2026-06-23T18:41:07.778504Z","end":"2026-06-23T18:41:08.057409Z","steps":["trace[1593801072] 'agreement among raft nodes before linearized reading' (duration: 278.835951ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:41:28.805597Z","caller":"traceutil/trace.go:171","msg":"trace[552102300] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"113.920662ms","start":"2026-06-23T18:41:28.691653Z","end":"2026-06-23T18:41:28.805574Z","steps":["trace[552102300] 'process raft request' (duration: 113.616907ms)"],"step_count":1} {"level":"info","ts":"2026-06-23T18:42:06.908468Z","caller":"traceutil/trace.go:171","msg":"trace[1365004506] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"101.194636ms","start":"2026-06-23T18:42:06.807228Z","end":"2026-06-23T18:42:06.908422Z","steps":["trace[1365004506] 'process raft request' (duration: 100.897302ms)"],"step_count":1}