{"level":"warn","ts":"2026-07-06T23:15:58.060613Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:15:58.060717Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.3: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.3:2380","--initial-cluster=instance=https://199.204.45.3:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.3:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.3: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-07-06T23:15:58.06083Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-06T23:15:58.060893Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-06T23:15:58.060943Z","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-07-06T23:15:58.061825Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"]} {"level":"info","ts":"2026-07-06T23:15:58.061986Z","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.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.3: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-07-06T23:15:58.064559Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.257815ms"} {"level":"info","ts":"2026-07-06T23:15:58.074175Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"597401b06ce1cf3d","cluster-id":"dfc54657ad8a2432"} {"level":"info","ts":"2026-07-06T23:15:58.074267Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=()"} {"level":"info","ts":"2026-07-06T23:15:58.074291Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 0"} {"level":"info","ts":"2026-07-06T23:15:58.074309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 597401b06ce1cf3d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-06T23:15:58.074324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became follower at term 1"} {"level":"info","ts":"2026-07-06T23:15:58.074371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"warn","ts":"2026-07-06T23:15:58.080905Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-06T23:15:58.082932Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-06T23:15:58.083855Z","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-07-06T23:15:58.086008Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"597401b06ce1cf3d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-06T23:15:58.086254Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"597401b06ce1cf3d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-06T23:15:58.086357Z","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-07-06T23:15:58.086415Z","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-07-06T23:15:58.08643Z","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-07-06T23:15:58.087005Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d switched to configuration voters=(6445778823926632253)"} {"level":"info","ts":"2026-07-06T23:15:58.090445Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","added-peer-id":"597401b06ce1cf3d","added-peer-peer-urls":["https://199.204.45.3:2380"]} {"level":"info","ts":"2026-07-06T23:15:58.101885Z","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-07-06T23:15:58.102006Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-06T23:15:58.102079Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.3:2380"} {"level":"info","ts":"2026-07-06T23:15:58.10212Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"597401b06ce1cf3d","initial-advertise-peer-urls":["https://199.204.45.3:2380"],"listen-peer-urls":["https://199.204.45.3:2380"],"advertise-client-urls":["https://199.204.45.3:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.3:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-06T23:15:58.102157Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-06T23:15:58.375152Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d is starting a new election at term 1"} {"level":"info","ts":"2026-07-06T23:15:58.375203Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-06T23:15:58.375236Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgPreVoteResp from 597401b06ce1cf3d at term 1"} {"level":"info","ts":"2026-07-06T23:15:58.375254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became candidate at term 2"} {"level":"info","ts":"2026-07-06T23:15:58.375265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d received MsgVoteResp from 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-06T23:15:58.375282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"597401b06ce1cf3d became leader at term 2"} {"level":"info","ts":"2026-07-06T23:15:58.375295Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 597401b06ce1cf3d elected leader 597401b06ce1cf3d at term 2"} {"level":"info","ts":"2026-07-06T23:15:58.375954Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:15:58.376783Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:15:58.376869Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-06T23:15:58.376777Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"597401b06ce1cf3d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.3:2379]}","request-path":"/0/members/597401b06ce1cf3d/attributes","cluster-id":"dfc54657ad8a2432","publish-timeout":"7s"} {"level":"info","ts":"2026-07-06T23:15:58.377204Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-06T23:15:58.377281Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-06T23:15:58.380053Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-06T23:15:58.380141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.3:2379"} {"level":"info","ts":"2026-07-06T23:15:58.38495Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"dfc54657ad8a2432","local-member-id":"597401b06ce1cf3d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:15:58.385042Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:15:58.385074Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-06T23:16:04.350175Z","caller":"traceutil/trace.go:171","msg":"trace[1767672281] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"106.502919ms","start":"2026-07-06T23:16:04.243641Z","end":"2026-07-06T23:16:04.350144Z","steps":["trace[1767672281] 'process raft request' (duration: 106.324645ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.350752Z","caller":"traceutil/trace.go:171","msg":"trace[1758834599] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"106.908729ms","start":"2026-07-06T23:16:04.243747Z","end":"2026-07-06T23:16:04.350655Z","steps":["trace[1758834599] 'process raft request' (duration: 106.794867ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.733191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.812177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:04.733328Z","caller":"traceutil/trace.go:171","msg":"trace[1624694431] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:291; }","duration":"322.989752ms","start":"2026-07-06T23:16:04.410306Z","end":"2026-07-06T23:16:04.733296Z","steps":["trace[1624694431] 'range keys from in-memory index tree' (duration: 322.739326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.733388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:04.410287Z","time spent":"323.076723ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-06T23:16:04.733412Z","caller":"traceutil/trace.go:171","msg":"trace[450284102] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"266.554ms","start":"2026-07-06T23:16:04.466813Z","end":"2026-07-06T23:16:04.733367Z","steps":["trace[450284102] 'process raft request' (duration: 227.108269ms)","trace[450284102] 'compare' (duration: 39.39668ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:04.733821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.782745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:04.733865Z","caller":"traceutil/trace.go:171","msg":"trace[1982669484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:291; }","duration":"278.914288ms","start":"2026-07-06T23:16:04.454937Z","end":"2026-07-06T23:16:04.733851Z","steps":["trace[1982669484] 'range keys from in-memory index tree' (duration: 278.627501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.734551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.773617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:5887"} {"level":"info","ts":"2026-07-06T23:16:04.734631Z","caller":"traceutil/trace.go:171","msg":"trace[2087371154] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:291; }","duration":"269.888721ms","start":"2026-07-06T23:16:04.464725Z","end":"2026-07-06T23:16:04.734614Z","steps":["trace[2087371154] 'range keys from in-memory index tree' (duration: 269.697545ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.944389Z","caller":"traceutil/trace.go:171","msg":"trace[272508229] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"477.395366ms","start":"2026-07-06T23:16:04.466978Z","end":"2026-07-06T23:16:04.944374Z","steps":["trace[272508229] 'process raft request' (duration: 477.213922ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.944561Z","caller":"traceutil/trace.go:171","msg":"trace[1913951201] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"477.417977ms","start":"2026-07-06T23:16:04.467019Z","end":"2026-07-06T23:16:04.944437Z","steps":["trace[1913951201] 'process raft request' (duration: 477.249063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.944568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.009791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:5513"} {"level":"info","ts":"2026-07-06T23:16:04.944686Z","caller":"traceutil/trace.go:171","msg":"trace[1365500213] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:291; }","duration":"209.180355ms","start":"2026-07-06T23:16:04.735492Z","end":"2026-07-06T23:16:04.944672Z","steps":["trace[1365500213] 'agreement among raft nodes before linearized reading' (duration: 208.95106ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.944393Z","caller":"traceutil/trace.go:171","msg":"trace[672249449] linearizableReadLoop","detail":"{readStateIndex:303; appliedIndex:303; }","duration":"208.819067ms","start":"2026-07-06T23:16:04.735535Z","end":"2026-07-06T23:16:04.944354Z","steps":["trace[672249449] 'read index received' (duration: 208.811887ms)","trace[672249449] 'applied index is now lower than readState.Index' (duration: 5.38µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:04.94494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:04.466971Z","time spent":"477.492738ms","remote":"127.0.0.1:57772","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":28,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:16:04.945051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:04.467015Z","time spent":"477.687083ms","remote":"127.0.0.1:57772","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-07-06T23:16:04.945102Z","caller":"traceutil/trace.go:171","msg":"trace[1299671670] transaction","detail":"{read_only:false; number_of_response:0; response_revision:291; }","duration":"476.845543ms","start":"2026-07-06T23:16:04.467474Z","end":"2026-07-06T23:16:04.94432Z","steps":["trace[1299671670] 'process raft request' (duration: 476.816522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:04.945164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:04.467442Z","time spent":"477.692204ms","remote":"127.0.0.1:57772","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-07-06T23:16:04.972502Z","caller":"traceutil/trace.go:171","msg":"trace[2070054203] transaction","detail":"{read_only:false; response_revision:293; number_of_response:1; }","duration":"234.764713ms","start":"2026-07-06T23:16:04.737719Z","end":"2026-07-06T23:16:04.972483Z","steps":["trace[2070054203] 'process raft request' (duration: 234.551027ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.972666Z","caller":"traceutil/trace.go:171","msg":"trace[1765896599] transaction","detail":"{read_only:false; response_revision:292; number_of_response:1; }","duration":"234.995239ms","start":"2026-07-06T23:16:04.737656Z","end":"2026-07-06T23:16:04.972652Z","steps":["trace[1765896599] 'process raft request' (duration: 234.478836ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:04.97269Z","caller":"traceutil/trace.go:171","msg":"trace[646087817] transaction","detail":"{read_only:false; response_revision:294; number_of_response:1; }","duration":"220.836427ms","start":"2026-07-06T23:16:04.751836Z","end":"2026-07-06T23:16:04.972673Z","steps":["trace[646087817] 'process raft request' (duration: 220.53415ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:36.092409Z","caller":"traceutil/trace.go:171","msg":"trace[1025428542] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"489.688679ms","start":"2026-07-06T23:16:35.60268Z","end":"2026-07-06T23:16:36.092369Z","steps":["trace[1025428542] 'process raft request' (duration: 468.044042ms)","trace[1025428542] 'compare' (duration: 21.375251ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:36.092704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:35.602663Z","time spent":"489.813592ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:36.093432Z","caller":"traceutil/trace.go:171","msg":"trace[1926319554] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:587; }","duration":"406.957388ms","start":"2026-07-06T23:16:35.68646Z","end":"2026-07-06T23:16:36.093417Z","steps":["trace[1926319554] 'read index received' (duration: 384.149211ms)","trace[1926319554] 'applied index is now lower than readState.Index' (duration: 22.807147ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:36.093526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.05881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:36.093554Z","caller":"traceutil/trace.go:171","msg":"trace[188649642] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:569; }","duration":"407.119302ms","start":"2026-07-06T23:16:35.686426Z","end":"2026-07-06T23:16:36.093545Z","steps":["trace[188649642] 'agreement among raft nodes before linearized reading' (duration: 407.050761ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:36.093582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:35.686413Z","time spent":"407.163683ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:16:36.093546Z","caller":"traceutil/trace.go:171","msg":"trace[616186932] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"477.709376ms","start":"2026-07-06T23:16:35.615796Z","end":"2026-07-06T23:16:36.093506Z","steps":["trace[616186932] 'process raft request' (duration: 477.425069ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:36.093629Z","caller":"traceutil/trace.go:171","msg":"trace[1200002500] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"473.90559ms","start":"2026-07-06T23:16:35.619684Z","end":"2026-07-06T23:16:36.093589Z","steps":["trace[1200002500] 'process raft request' (duration: 473.642734ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:36.093707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:35.615779Z","time spent":"477.850479ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-06T23:16:36.093837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:35.61967Z","time spent":"474.051574ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:37.240338Z","caller":"traceutil/trace.go:171","msg":"trace[1991255363] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"323.484267ms","start":"2026-07-06T23:16:36.916827Z","end":"2026-07-06T23:16:37.240311Z","steps":["trace[1991255363] 'process raft request' (duration: 319.527147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:37.240502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:36.916794Z","time spent":"323.628351ms","remote":"127.0.0.1:57652","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:16:37.24468Z","caller":"traceutil/trace.go:171","msg":"trace[1190889373] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"238.043658ms","start":"2026-07-06T23:16:37.006617Z","end":"2026-07-06T23:16:37.244661Z","steps":["trace[1190889373] 'process raft request' (duration: 234.700644ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:41.477353Z","caller":"traceutil/trace.go:171","msg":"trace[1845641599] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"177.660571ms","start":"2026-07-06T23:16:41.299658Z","end":"2026-07-06T23:16:41.477319Z","steps":["trace[1845641599] 'process raft request' (duration: 95.420232ms)","trace[1845641599] 'compare' (duration: 82.063385ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:41.477436Z","caller":"traceutil/trace.go:171","msg":"trace[185534878] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"175.468216ms","start":"2026-07-06T23:16:41.301949Z","end":"2026-07-06T23:16:41.477417Z","steps":["trace[185534878] 'read index received' (duration: 84.100216ms)","trace[185534878] 'applied index is now lower than readState.Index' (duration: 91.36696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:41.477699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.744153ms","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-07-06T23:16:41.477744Z","caller":"traceutil/trace.go:171","msg":"trace[523445731] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"175.837076ms","start":"2026-07-06T23:16:41.301891Z","end":"2026-07-06T23:16:41.477728Z","steps":["trace[523445731] 'agreement among raft nodes before linearized reading' (duration: 175.60395ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:42.370694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.021287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:42.370827Z","caller":"traceutil/trace.go:171","msg":"trace[1977993750] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/envoy-gateway-topology-injector.envoy-gateway-system; range_end:; response_count:0; response_revision:637; }","duration":"200.236582ms","start":"2026-07-06T23:16:42.170562Z","end":"2026-07-06T23:16:42.370798Z","steps":["trace[1977993750] 'range keys from in-memory index tree' (duration: 199.903053ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:42.833575Z","caller":"traceutil/trace.go:171","msg":"trace[923393575] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"270.348935ms","start":"2026-07-06T23:16:42.563192Z","end":"2026-07-06T23:16:42.833541Z","steps":["trace[923393575] 'read index received' (duration: 270.197711ms)","trace[923393575] 'applied index is now lower than readState.Index' (duration: 149.984µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:16:42.833649Z","caller":"traceutil/trace.go:171","msg":"trace[1220984858] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"272.244872ms","start":"2026-07-06T23:16:42.561385Z","end":"2026-07-06T23:16:42.83363Z","steps":["trace[1220984858] 'process raft request' (duration: 271.935624ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:42.833846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.623602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-l4zps\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-07-06T23:16:42.834037Z","caller":"traceutil/trace.go:171","msg":"trace[323800634] range","detail":"{range_begin:/registry/pods/kube-system/cilium-l4zps; range_end:; response_count:1; response_revision:638; }","duration":"270.881268ms","start":"2026-07-06T23:16:42.563138Z","end":"2026-07-06T23:16:42.834019Z","steps":["trace[323800634] 'agreement among raft nodes before linearized reading' (duration: 270.609561ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:16:43.065434Z","caller":"traceutil/trace.go:171","msg":"trace[450230326] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"231.697237ms","start":"2026-07-06T23:16:42.833706Z","end":"2026-07-06T23:16:43.065403Z","steps":["trace[450230326] 'read index received' (duration: 226.602428ms)","trace[450230326] 'applied index is now lower than readState.Index' (duration: 5.093109ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:16:43.065659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.873685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:16:43.065702Z","caller":"traceutil/trace.go:171","msg":"trace[1416600766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:639; }","duration":"476.955067ms","start":"2026-07-06T23:16:42.588735Z","end":"2026-07-06T23:16:43.06569Z","steps":["trace[1416600766] 'agreement among raft nodes before linearized reading' (duration: 476.776552ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:43.06574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:42.58872Z","time spent":"477.012029ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-06T23:16:43.065992Z","caller":"traceutil/trace.go:171","msg":"trace[1925642792] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"298.999658ms","start":"2026-07-06T23:16:42.766939Z","end":"2026-07-06T23:16:43.065939Z","steps":["trace[1925642792] 'process raft request' (duration: 293.673394ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:43.066052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.110463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:16:43.066179Z","caller":"traceutil/trace.go:171","msg":"trace[1340064004] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"377.325249ms","start":"2026-07-06T23:16:42.68882Z","end":"2026-07-06T23:16:43.066145Z","steps":["trace[1340064004] 'agreement among raft nodes before linearized reading' (duration: 377.118563ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:16:43.06623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:16:42.688775Z","time spent":"377.445761ms","remote":"127.0.0.1:57602","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-06T23:16:43.082063Z","caller":"traceutil/trace.go:171","msg":"trace[1895283170] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"232.848797ms","start":"2026-07-06T23:16:42.849161Z","end":"2026-07-06T23:16:43.08201Z","steps":["trace[1895283170] 'process raft request' (duration: 232.707883ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:05.141127Z","caller":"traceutil/trace.go:171","msg":"trace[1042211797] transaction","detail":"{read_only:false; response_revision:1123; number_of_response:1; }","duration":"168.02707ms","start":"2026-07-06T23:18:04.973077Z","end":"2026-07-06T23:18:05.141104Z","steps":["trace[1042211797] 'process raft request' (duration: 167.892536ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:09.915875Z","caller":"traceutil/trace.go:171","msg":"trace[1299497145] linearizableReadLoop","detail":"{readStateIndex:1181; appliedIndex:1180; }","duration":"320.558126ms","start":"2026-07-06T23:18:09.595295Z","end":"2026-07-06T23:18:09.915853Z","steps":["trace[1299497145] 'read index received' (duration: 320.392742ms)","trace[1299497145] 'applied index is now lower than readState.Index' (duration: 164.454µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:09.915987Z","caller":"traceutil/trace.go:171","msg":"trace[1151590951] transaction","detail":"{read_only:false; response_revision:1138; number_of_response:1; }","duration":"613.864968ms","start":"2026-07-06T23:18:09.302109Z","end":"2026-07-06T23:18:09.915974Z","steps":["trace[1151590951] 'process raft request' (duration: 613.625342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:09.916264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:09.30209Z","time spent":"613.986961ms","remote":"127.0.0.1:57842","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-07-06T23:18:09.916374Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.038538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:18:09.916525Z","caller":"traceutil/trace.go:171","msg":"trace[101193071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1138; }","duration":"321.212773ms","start":"2026-07-06T23:18:09.595248Z","end":"2026-07-06T23:18:09.916461Z","steps":["trace[101193071] 'agreement among raft nodes before linearized reading' (duration: 320.881054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:09.916571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:09.595229Z","time spent":"321.330955ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:18:09.916705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.419009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:18:09.916738Z","caller":"traceutil/trace.go:171","msg":"trace[1555957519] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1138; }","duration":"227.48509ms","start":"2026-07-06T23:18:09.68924Z","end":"2026-07-06T23:18:09.916725Z","steps":["trace[1555957519] 'agreement among raft nodes before linearized reading' (duration: 226.811753ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:10.213123Z","caller":"traceutil/trace.go:171","msg":"trace[263923889] linearizableReadLoop","detail":"{readStateIndex:1182; appliedIndex:1181; }","duration":"283.72178ms","start":"2026-07-06T23:18:09.929377Z","end":"2026-07-06T23:18:10.213099Z","steps":["trace[263923889] 'read index received' (duration: 283.540485ms)","trace[263923889] 'applied index is now lower than readState.Index' (duration: 180.165µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:10.213582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.182721ms","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-07-06T23:18:10.213664Z","caller":"traceutil/trace.go:171","msg":"trace[952632201] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1139; }","duration":"284.321175ms","start":"2026-07-06T23:18:09.929324Z","end":"2026-07-06T23:18:10.213645Z","steps":["trace[952632201] 'agreement among raft nodes before linearized reading' (duration: 284.047108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:10.213659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.406732ms","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-07-06T23:18:10.213853Z","caller":"traceutil/trace.go:171","msg":"trace[1791735978] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1139; }","duration":"185.546305ms","start":"2026-07-06T23:18:10.028161Z","end":"2026-07-06T23:18:10.213707Z","steps":["trace[1791735978] 'agreement among raft nodes before linearized reading' (duration: 185.32985ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:10.214189Z","caller":"traceutil/trace.go:171","msg":"trace[1369474807] transaction","detail":"{read_only:false; response_revision:1139; number_of_response:1; }","duration":"293.646222ms","start":"2026-07-06T23:18:09.92047Z","end":"2026-07-06T23:18:10.214117Z","steps":["trace[1369474807] 'process raft request' (duration: 292.457251ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:11.496988Z","caller":"traceutil/trace.go:171","msg":"trace[1390669381] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"325.641035ms","start":"2026-07-06T23:18:11.17132Z","end":"2026-07-06T23:18:11.496961Z","steps":["trace[1390669381] 'process raft request' (duration: 325.451431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:11.497208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:11.171301Z","time spent":"325.760228ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:18:11.553053Z","caller":"traceutil/trace.go:171","msg":"trace[1305711583] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"380.361975ms","start":"2026-07-06T23:18:11.172674Z","end":"2026-07-06T23:18:11.553036Z","steps":["trace[1305711583] 'process raft request' (duration: 376.801695ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:11.553199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:11.172657Z","time spent":"380.449798ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:18:12.358282Z","caller":"traceutil/trace.go:171","msg":"trace[1633681074] linearizableReadLoop","detail":"{readStateIndex:1190; appliedIndex:1189; }","duration":"135.817792ms","start":"2026-07-06T23:18:12.222439Z","end":"2026-07-06T23:18:12.358257Z","steps":["trace[1633681074] 'read index received' (duration: 135.600696ms)","trace[1633681074] 'applied index is now lower than readState.Index' (duration: 213.976µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:12.358326Z","caller":"traceutil/trace.go:171","msg":"trace[312529438] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"136.671443ms","start":"2026-07-06T23:18:12.221631Z","end":"2026-07-06T23:18:12.358303Z","steps":["trace[312529438] 'process raft request' (duration: 136.462177ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:12.358523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.022032ms","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-07-06T23:18:12.358599Z","caller":"traceutil/trace.go:171","msg":"trace[1217279947] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1146; }","duration":"135.153525ms","start":"2026-07-06T23:18:12.223429Z","end":"2026-07-06T23:18:12.358583Z","steps":["trace[1217279947] 'agreement among raft nodes before linearized reading' (duration: 134.97473ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:12.358524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.057848ms","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-07-06T23:18:12.358727Z","caller":"traceutil/trace.go:171","msg":"trace[435304300] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1146; }","duration":"136.294943ms","start":"2026-07-06T23:18:12.222414Z","end":"2026-07-06T23:18:12.358709Z","steps":["trace[435304300] 'agreement among raft nodes before linearized reading' (duration: 135.933994ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:28.922503Z","caller":"traceutil/trace.go:171","msg":"trace[139049061] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"498.60168ms","start":"2026-07-06T23:18:28.423875Z","end":"2026-07-06T23:18:28.922477Z","steps":["trace[139049061] 'process raft request' (duration: 498.468416ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:28.922563Z","caller":"traceutil/trace.go:171","msg":"trace[1290927368] linearizableReadLoop","detail":"{readStateIndex:1299; appliedIndex:1299; }","duration":"497.961593ms","start":"2026-07-06T23:18:28.424566Z","end":"2026-07-06T23:18:28.922528Z","steps":["trace[1290927368] 'read index received' (duration: 497.951773ms)","trace[1290927368] 'applied index is now lower than readState.Index' (duration: 7.58µs)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:28.922682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:28.423836Z","time spent":"498.747224ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:18:28.922845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.399232ms","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-07-06T23:18:28.922893Z","caller":"traceutil/trace.go:171","msg":"trace[979560797] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1250; }","duration":"496.494044ms","start":"2026-07-06T23:18:28.426377Z","end":"2026-07-06T23:18:28.922871Z","steps":["trace[979560797] 'agreement among raft nodes before linearized reading' (duration: 496.298759ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:28.922932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:28.426367Z","time spent":"496.556737ms","remote":"127.0.0.1:57842","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-07-06T23:18:28.922944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"498.353133ms","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-07-06T23:18:28.923018Z","caller":"traceutil/trace.go:171","msg":"trace[1889251264] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1250; }","duration":"498.476757ms","start":"2026-07-06T23:18:28.424523Z","end":"2026-07-06T23:18:28.923Z","steps":["trace[1889251264] 'agreement among raft nodes before linearized reading' (duration: 498.279341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:28.923057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:28.424502Z","time spent":"498.547758ms","remote":"127.0.0.1:57842","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-07-06T23:18:29.409052Z","caller":"traceutil/trace.go:171","msg":"trace[1178707252] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"482.556919ms","start":"2026-07-06T23:18:28.926468Z","end":"2026-07-06T23:18:29.409025Z","steps":["trace[1178707252] 'process raft request' (duration: 482.425236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:29.409441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:28.926446Z","time spent":"482.904469ms","remote":"127.0.0.1:57842","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-07-06T23:18:29.409628Z","caller":"traceutil/trace.go:171","msg":"trace[574588502] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"481.446011ms","start":"2026-07-06T23:18:28.928166Z","end":"2026-07-06T23:18:29.409612Z","steps":["trace[574588502] 'process raft request' (duration: 481.351458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:29.410066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:28.928136Z","time spent":"481.759579ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:18:41.740483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.345078ms","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-07-06T23:18:41.740602Z","caller":"traceutil/trace.go:171","msg":"trace[6669758] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1291; }","duration":"100.592355ms","start":"2026-07-06T23:18:41.639985Z","end":"2026-07-06T23:18:41.740577Z","steps":["trace[6669758] 'range keys from in-memory index tree' (duration: 100.052931ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:43.426935Z","caller":"traceutil/trace.go:171","msg":"trace[1642045034] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"437.54861ms","start":"2026-07-06T23:18:42.989362Z","end":"2026-07-06T23:18:43.426911Z","steps":["trace[1642045034] 'process raft request' (duration: 437.407077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:43.427097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:42.989344Z","time spent":"437.662244ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:18:43.540998Z","caller":"traceutil/trace.go:171","msg":"trace[449757434] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"323.33561ms","start":"2026-07-06T23:18:43.217624Z","end":"2026-07-06T23:18:43.54096Z","steps":["trace[449757434] 'process raft request' (duration: 323.008901ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:43.541189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:43.217583Z","time spent":"323.505824ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:18:45.977294Z","caller":"traceutil/trace.go:171","msg":"trace[1774357295] linearizableReadLoop","detail":"{readStateIndex:1355; appliedIndex:1354; }","duration":"408.87759ms","start":"2026-07-06T23:18:45.568388Z","end":"2026-07-06T23:18:45.977266Z","steps":["trace[1774357295] 'read index received' (duration: 408.560882ms)","trace[1774357295] 'applied index is now lower than readState.Index' (duration: 315.398µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:45.977453Z","caller":"traceutil/trace.go:171","msg":"trace[652715125] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"539.953114ms","start":"2026-07-06T23:18:45.437484Z","end":"2026-07-06T23:18:45.977438Z","steps":["trace[652715125] 'process raft request' (duration: 539.545763ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:45.977666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:45.437462Z","time spent":"540.022765ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:18:45.97803Z","caller":"traceutil/trace.go:171","msg":"trace[1404440373] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"206.654663ms","start":"2026-07-06T23:18:45.771325Z","end":"2026-07-06T23:18:45.97798Z","steps":["trace[1404440373] 'process raft request' (duration: 205.881223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:45.978154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.781803ms","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":"warn","ts":"2026-07-06T23:18:45.97818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.773644ms","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-07-06T23:18:45.978215Z","caller":"traceutil/trace.go:171","msg":"trace[1487671870] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1304; }","duration":"136.896226ms","start":"2026-07-06T23:18:45.841302Z","end":"2026-07-06T23:18:45.978198Z","steps":["trace[1487671870] 'agreement among raft nodes before linearized reading' (duration: 136.67995ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:45.978243Z","caller":"traceutil/trace.go:171","msg":"trace[2116418420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"409.867136ms","start":"2026-07-06T23:18:45.568355Z","end":"2026-07-06T23:18:45.978222Z","steps":["trace[2116418420] 'agreement among raft nodes before linearized reading' (duration: 409.483456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:45.978222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.071882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-06T23:18:45.978295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:45.568338Z","time spent":"409.946118ms","remote":"127.0.0.1:57842","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-07-06T23:18:45.978323Z","caller":"traceutil/trace.go:171","msg":"trace[1380156423] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"289.239076ms","start":"2026-07-06T23:18:45.689059Z","end":"2026-07-06T23:18:45.978298Z","steps":["trace[1380156423] 'agreement among raft nodes before linearized reading' (duration: 289.028081ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:45.978454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.04431ms","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-07-06T23:18:45.978732Z","caller":"traceutil/trace.go:171","msg":"trace[207428166] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1304; }","duration":"410.351648ms","start":"2026-07-06T23:18:45.568367Z","end":"2026-07-06T23:18:45.978719Z","steps":["trace[207428166] 'agreement among raft nodes before linearized reading' (duration: 409.547717ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:45.978769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:45.568351Z","time spent":"410.4068ms","remote":"127.0.0.1:57842","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-07-06T23:18:46.220403Z","caller":"traceutil/trace.go:171","msg":"trace[1348941937] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"182.957862ms","start":"2026-07-06T23:18:46.037396Z","end":"2026-07-06T23:18:46.220354Z","steps":["trace[1348941937] 'process raft request' (duration: 103.0565ms)","trace[1348941937] 'compare' (duration: 79.706087ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:47.772587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"820.930852ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-06T23:18:47.773534Z","caller":"traceutil/trace.go:171","msg":"trace[1176382530] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1361; }","duration":"102.464844ms","start":"2026-07-06T23:18:47.671048Z","end":"2026-07-06T23:18:47.773513Z","steps":["trace[1176382530] 'read index received' (duration: 82.756348ms)","trace[1176382530] 'applied index is now lower than readState.Index' (duration: 19.706786ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:47.773566Z","caller":"traceutil/trace.go:171","msg":"trace[425111516] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"496.575027ms","start":"2026-07-06T23:18:47.276954Z","end":"2026-07-06T23:18:47.773529Z","steps":["trace[425111516] 'process raft request' (duration: 495.736615ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:47.773726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:47.276929Z","time spent":"496.69986ms","remote":"127.0.0.1:57842","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-07-06T23:18:48.292793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.625571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:18:48.292896Z","caller":"traceutil/trace.go:171","msg":"trace[1144813939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1310; }","duration":"306.771415ms","start":"2026-07-06T23:18:47.986106Z","end":"2026-07-06T23:18:48.292878Z","steps":["trace[1144813939] 'range keys from in-memory index tree' (duration: 306.329154ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:48.292938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:47.986087Z","time spent":"306.841687ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-06T23:18:48.292937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.341193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:18:48.292986Z","caller":"traceutil/trace.go:171","msg":"trace[348164576] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1310; }","duration":"603.389554ms","start":"2026-07-06T23:18:47.689581Z","end":"2026-07-06T23:18:48.292971Z","steps":["trace[348164576] 'agreement among raft nodes before linearized reading' (duration: 84.047621ms)","trace[348164576] 'range keys from in-memory index tree' (duration: 519.264652ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:48.293019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:47.689519Z","time spent":"603.492288ms","remote":"127.0.0.1:57602","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-07-06T23:18:48.293215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.146536ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-06T23:18:48.293322Z","caller":"traceutil/trace.go:171","msg":"trace[1126773886] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1310; }","duration":"622.342411ms","start":"2026-07-06T23:18:47.670956Z","end":"2026-07-06T23:18:48.293298Z","steps":["trace[1126773886] 'agreement among raft nodes before linearized reading' (duration: 102.652539ms)","trace[1126773886] 'count revisions from in-memory index tree' (duration: 519.525518ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:48.293312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.309296ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"warn","ts":"2026-07-06T23:18:48.293369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:47.670921Z","time spent":"622.437494ms","remote":"127.0.0.1:57844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":30,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"info","ts":"2026-07-06T23:18:48.293386Z","caller":"traceutil/trace.go:171","msg":"trace[297696135] linearizableReadLoop","detail":"{readStateIndex:1364; appliedIndex:1363; }","duration":"290.317464ms","start":"2026-07-06T23:18:48.003058Z","end":"2026-07-06T23:18:48.293375Z","steps":["trace[297696135] 'read index received' (duration: 131.778282ms)","trace[297696135] 'applied index is now lower than readState.Index' (duration: 158.538332ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:48.293475Z","caller":"traceutil/trace.go:171","msg":"trace[494061619] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"303.16595ms","start":"2026-07-06T23:18:47.990298Z","end":"2026-07-06T23:18:48.293464Z","steps":["trace[494061619] 'process raft request' (duration: 144.634368ms)","trace[494061619] 'compare' (duration: 157.757352ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:18:48.293526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:47.990258Z","time spent":"303.239682ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:18:48.293545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.504033ms","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-07-06T23:18:48.29358Z","caller":"traceutil/trace.go:171","msg":"trace[570359242] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1311; }","duration":"289.548684ms","start":"2026-07-06T23:18:48.004021Z","end":"2026-07-06T23:18:48.29357Z","steps":["trace[570359242] 'agreement among raft nodes before linearized reading' (duration: 289.450352ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:48.293703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.650143ms","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-07-06T23:18:48.293727Z","caller":"traceutil/trace.go:171","msg":"trace[1938130034] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1311; }","duration":"290.731595ms","start":"2026-07-06T23:18:48.002987Z","end":"2026-07-06T23:18:48.293718Z","steps":["trace[1938130034] 'agreement among raft nodes before linearized reading' (duration: 290.661113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:48.293831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.7597ms","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-07-06T23:18:48.293853Z","caller":"traceutil/trace.go:171","msg":"trace[1905338848] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1311; }","duration":"289.78773ms","start":"2026-07-06T23:18:48.004059Z","end":"2026-07-06T23:18:48.293847Z","steps":["trace[1905338848] 'agreement among raft nodes before linearized reading' (duration: 289.724909ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:48.458669Z","caller":"traceutil/trace.go:171","msg":"trace[353610076] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"160.149485ms","start":"2026-07-06T23:18:48.298495Z","end":"2026-07-06T23:18:48.458644Z","steps":["trace[353610076] 'process raft request' (duration: 160.080363ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:48.458712Z","caller":"traceutil/trace.go:171","msg":"trace[1194901200] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"160.240647ms","start":"2026-07-06T23:18:48.298441Z","end":"2026-07-06T23:18:48.458682Z","steps":["trace[1194901200] 'process raft request' (duration: 160.093323ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:48.458742Z","caller":"traceutil/trace.go:171","msg":"trace[1190738529] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"160.660188ms","start":"2026-07-06T23:18:48.298026Z","end":"2026-07-06T23:18:48.458686Z","steps":["trace[1190738529] 'process raft request' (duration: 152.956686ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:48.459045Z","caller":"traceutil/trace.go:171","msg":"trace[295878891] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"160.368721ms","start":"2026-07-06T23:18:48.298637Z","end":"2026-07-06T23:18:48.459005Z","steps":["trace[295878891] 'process raft request' (duration: 159.97263ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:48.970253Z","caller":"traceutil/trace.go:171","msg":"trace[953571201] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"230.137227ms","start":"2026-07-06T23:18:48.740084Z","end":"2026-07-06T23:18:48.970222Z","steps":["trace[953571201] 'process raft request' (duration: 229.954973ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:18:49.396482Z","caller":"traceutil/trace.go:171","msg":"trace[1215092073] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"422.875976ms","start":"2026-07-06T23:18:48.973585Z","end":"2026-07-06T23:18:49.396461Z","steps":["trace[1215092073] 'process raft request' (duration: 422.753403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:49.396601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:48.973571Z","time spent":"422.971069ms","remote":"127.0.0.1:57676","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":822,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-06T23:18:59.910544Z","caller":"traceutil/trace.go:171","msg":"trace[1541618984] linearizableReadLoop","detail":"{readStateIndex:1467; appliedIndex:1466; }","duration":"221.902583ms","start":"2026-07-06T23:18:59.688615Z","end":"2026-07-06T23:18:59.910517Z","steps":["trace[1541618984] 'read index received' (duration: 221.632846ms)","trace[1541618984] 'applied index is now lower than readState.Index' (duration: 268.557µs)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:18:59.910692Z","caller":"traceutil/trace.go:171","msg":"trace[1001142855] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"382.102079ms","start":"2026-07-06T23:18:59.528578Z","end":"2026-07-06T23:18:59.910681Z","steps":["trace[1001142855] 'process raft request' (duration: 381.764ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:59.910764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.137709ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-06T23:18:59.910802Z","caller":"traceutil/trace.go:171","msg":"trace[1374804053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1412; }","duration":"222.225301ms","start":"2026-07-06T23:18:59.688564Z","end":"2026-07-06T23:18:59.910789Z","steps":["trace[1374804053] 'agreement among raft nodes before linearized reading' (duration: 222.159139ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:18:59.910822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:59.528555Z","time spent":"382.16552ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-06T23:19:00.682157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.253155ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-06T23:19:00.682397Z","caller":"traceutil/trace.go:171","msg":"trace[1298069412] transaction","detail":"{read_only:false; response_revision:1414; number_of_response:1; }","duration":"343.658002ms","start":"2026-07-06T23:19:00.338713Z","end":"2026-07-06T23:19:00.682371Z","steps":["trace[1298069412] 'process raft request' (duration: 343.56746ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.682473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:19:00.338683Z","time spent":"343.755744ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:19:00.682478Z","caller":"traceutil/trace.go:171","msg":"trace[893417276] linearizableReadLoop","detail":"{readStateIndex:1468; appliedIndex:1467; }","duration":"543.023064ms","start":"2026-07-06T23:19:00.139384Z","end":"2026-07-06T23:19:00.682407Z","steps":["trace[893417276] 'read index received' (duration: 39.761µs)","trace[893417276] 'applied index is now lower than readState.Index' (duration: 542.980963ms)"],"step_count":2} {"level":"info","ts":"2026-07-06T23:19:00.68314Z","caller":"traceutil/trace.go:171","msg":"trace[1778665785] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"766.373574ms","start":"2026-07-06T23:18:59.915892Z","end":"2026-07-06T23:19:00.682265Z","steps":["trace[1778665785] 'process raft request' (duration: 184.920804ms)","trace[1778665785] 'compare' (duration: 580.985648ms)"],"step_count":2} {"level":"warn","ts":"2026-07-06T23:19:00.683253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.849205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-06T23:19:00.683289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:18:59.915864Z","time spent":"767.348319ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-06T23:19:00.683299Z","caller":"traceutil/trace.go:171","msg":"trace[1620185197] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1414; }","duration":"543.952958ms","start":"2026-07-06T23:19:00.139335Z","end":"2026-07-06T23:19:00.683288Z","steps":["trace[1620185197] 'agreement among raft nodes before linearized reading' (duration: 543.842085ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.68334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:19:00.139314Z","time spent":"544.01803ms","remote":"127.0.0.1:57664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-06T23:19:00.684076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.971379ms","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-07-06T23:19:00.684121Z","caller":"traceutil/trace.go:171","msg":"trace[814670908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1414; }","duration":"163.053821ms","start":"2026-07-06T23:19:00.521055Z","end":"2026-07-06T23:19:00.684109Z","steps":["trace[814670908] 'agreement among raft nodes before linearized reading' (duration: 162.914358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.684245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.857136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129842"} {"level":"info","ts":"2026-07-06T23:19:00.684298Z","caller":"traceutil/trace.go:171","msg":"trace[1952283405] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1414; }","duration":"540.942629ms","start":"2026-07-06T23:19:00.14334Z","end":"2026-07-06T23:19:00.684283Z","steps":["trace[1952283405] 'agreement among raft nodes before linearized reading' (duration: 540.631321ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.684334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:19:00.143323Z","time spent":"541.00332ms","remote":"127.0.0.1:57658","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129865,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-07-06T23:19:00.684456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.980956ms","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-07-06T23:19:00.684469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.447682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-06T23:19:00.684504Z","caller":"traceutil/trace.go:171","msg":"trace[400829138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1414; }","duration":"160.513564ms","start":"2026-07-06T23:19:00.523982Z","end":"2026-07-06T23:19:00.684495Z","steps":["trace[400829138] 'agreement among raft nodes before linearized reading' (duration: 160.385271ms)"],"step_count":1} {"level":"info","ts":"2026-07-06T23:19:00.684507Z","caller":"traceutil/trace.go:171","msg":"trace[553798664] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1414; }","duration":"161.10852ms","start":"2026-07-06T23:19:00.523383Z","end":"2026-07-06T23:19:00.684492Z","steps":["trace[553798664] 'agreement among raft nodes before linearized reading' (duration: 160.870054ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.684641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.113839ms","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-07-06T23:19:00.684666Z","caller":"traceutil/trace.go:171","msg":"trace[644608359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1414; }","duration":"303.176991ms","start":"2026-07-06T23:19:00.381481Z","end":"2026-07-06T23:19:00.684658Z","steps":["trace[644608359] 'agreement among raft nodes before linearized reading' (duration: 303.106179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-06T23:19:00.684697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-06T23:19:00.381459Z","time spent":"303.229632ms","remote":"127.0.0.1:57842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-06T23:19:00.684796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.764542ms","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-07-06T23:19:00.684818Z","caller":"traceutil/trace.go:171","msg":"trace[2116725865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1414; }","duration":"165.817574ms","start":"2026-07-06T23:19:00.518993Z","end":"2026-07-06T23:19:00.684811Z","steps":["trace[2116725865] 'agreement among raft nodes before linearized reading' (duration: 165.755832ms)"],"step_count":1}