{"level":"warn","ts":"2026-07-09T16:37:05.763145Z","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-09T16:37:05.763236Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.223: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.223:2380","--initial-cluster=instance=https://199.19.213.223:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.223:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.223: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-09T16:37:05.763382Z","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-09T16:37:05.763401Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-07-09T16:37:05.76346Z","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-09T16:37:05.764315Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"]} {"level":"info","ts":"2026-07-09T16:37:05.764463Z","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.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.223: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-09T16:37:05.772413Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.686085ms"} {"level":"info","ts":"2026-07-09T16:37:05.786485Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"12dd93b2fab6fcb","cluster-id":"8ac3bdb9c2c26b53"} {"level":"info","ts":"2026-07-09T16:37:05.78675Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T16:37:05.786824Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 0"} {"level":"info","ts":"2026-07-09T16:37:05.786848Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 12dd93b2fab6fcb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T16:37:05.786896Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became follower at term 1"} {"level":"info","ts":"2026-07-09T16:37:05.786987Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"warn","ts":"2026-07-09T16:37:05.793519Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T16:37:05.795637Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T16:37:05.797605Z","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-09T16:37:05.801579Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"12dd93b2fab6fcb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T16:37:05.804073Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"12dd93b2fab6fcb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T16:37:05.808074Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb switched to configuration voters=(84962816215969739)"} {"level":"info","ts":"2026-07-09T16:37:05.808204Z","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-09T16:37:05.808634Z","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-09T16:37:05.808664Z","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-09T16:37:05.808934Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","added-peer-id":"12dd93b2fab6fcb","added-peer-peer-urls":["https://199.19.213.223:2380"]} {"level":"info","ts":"2026-07-09T16:37:05.810187Z","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-09T16:37:05.810378Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-07-09T16:37:05.810413Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.223:2380"} {"level":"info","ts":"2026-07-09T16:37:05.81048Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"12dd93b2fab6fcb","initial-advertise-peer-urls":["https://199.19.213.223:2380"],"listen-peer-urls":["https://199.19.213.223:2380"],"advertise-client-urls":["https://199.19.213.223:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.223:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T16:37:05.810524Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T16:37:06.58823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T16:37:06.588375Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T16:37:06.588436Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgPreVoteResp from 12dd93b2fab6fcb at term 1"} {"level":"info","ts":"2026-07-09T16:37:06.588488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became candidate at term 2"} {"level":"info","ts":"2026-07-09T16:37:06.588526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb received MsgVoteResp from 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-07-09T16:37:06.588569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"12dd93b2fab6fcb became leader at term 2"} {"level":"info","ts":"2026-07-09T16:37:06.588609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 12dd93b2fab6fcb elected leader 12dd93b2fab6fcb at term 2"} {"level":"info","ts":"2026-07-09T16:37:06.590609Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"12dd93b2fab6fcb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.223:2379]}","request-path":"/0/members/12dd93b2fab6fcb/attributes","cluster-id":"8ac3bdb9c2c26b53","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T16:37:06.590927Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:37:06.592051Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:37:06.592559Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T16:37:06.592798Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T16:37:06.592852Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T16:37:06.592907Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8ac3bdb9c2c26b53","local-member-id":"12dd93b2fab6fcb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:37:06.593017Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:37:06.593075Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T16:37:06.595928Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.223:2379"} {"level":"info","ts":"2026-07-09T16:37:06.596591Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T16:37:08.049771Z","caller":"traceutil/trace.go:171","msg":"trace[1065951606] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"232.009525ms","start":"2026-07-09T16:37:07.817705Z","end":"2026-07-09T16:37:08.049714Z","steps":["trace[1065951606] 'process raft request' (duration: 231.662405ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.050025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.041254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.050236Z","caller":"traceutil/trace.go:171","msg":"trace[1465461016] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:0; response_revision:2; }","duration":"228.24471ms","start":"2026-07-09T16:37:07.821935Z","end":"2026-07-09T16:37:08.050179Z","steps":["trace[1465461016] 'agreement among raft nodes before linearized reading' (duration: 227.87984ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.05022Z","caller":"traceutil/trace.go:171","msg":"trace[1387494535] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"227.741916ms","start":"2026-07-09T16:37:07.82194Z","end":"2026-07-09T16:37:08.049682Z","steps":["trace[1387494535] 'read index received' (duration: 227.429698ms)","trace[1387494535] 'applied index is now lower than readState.Index' (duration: 311.048µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:08.066425Z","caller":"traceutil/trace.go:171","msg":"trace[2034783557] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"166.547612ms","start":"2026-07-09T16:37:07.899854Z","end":"2026-07-09T16:37:08.066402Z","steps":["trace[2034783557] 'process raft request' (duration: 166.45ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.066443Z","caller":"traceutil/trace.go:171","msg":"trace[1327739603] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"166.779018ms","start":"2026-07-09T16:37:07.899592Z","end":"2026-07-09T16:37:08.066371Z","steps":["trace[1327739603] 'process raft request' (duration: 166.642535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.066525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.015356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.066544Z","caller":"traceutil/trace.go:171","msg":"trace[184038488] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"166.710947ms","start":"2026-07-09T16:37:07.899786Z","end":"2026-07-09T16:37:08.066497Z","steps":["trace[184038488] 'process raft request' (duration: 166.494741ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.06667Z","caller":"traceutil/trace.go:171","msg":"trace[1429741777] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"171.849431ms","start":"2026-07-09T16:37:07.894805Z","end":"2026-07-09T16:37:08.066654Z","steps":["trace[1429741777] 'process raft request' (duration: 171.317716ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.066691Z","caller":"traceutil/trace.go:171","msg":"trace[1046064183] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:10; }","duration":"243.221611ms","start":"2026-07-09T16:37:07.823448Z","end":"2026-07-09T16:37:08.06667Z","steps":["trace[1046064183] 'agreement among raft nodes before linearized reading' (duration: 242.979074ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.06644Z","caller":"traceutil/trace.go:171","msg":"trace[1582780024] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"166.701577ms","start":"2026-07-09T16:37:07.899715Z","end":"2026-07-09T16:37:08.066416Z","steps":["trace[1582780024] 'process raft request' (duration: 166.548402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.066984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.478513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.223\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T16:37:08.066997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.295131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-09T16:37:08.067079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.303808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.067104Z","caller":"traceutil/trace.go:171","msg":"trace[2017430376] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"195.405472ms","start":"2026-07-09T16:37:07.871693Z","end":"2026-07-09T16:37:08.067099Z","steps":["trace[2017430376] 'agreement among raft nodes before linearized reading' (duration: 195.33408ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.067012Z","caller":"traceutil/trace.go:171","msg":"trace[1619501438] range","detail":"{range_begin:/registry/masterleases/199.19.213.223; range_end:; response_count:0; response_revision:10; }","duration":"174.555196ms","start":"2026-07-09T16:37:07.892451Z","end":"2026-07-09T16:37:08.067006Z","steps":["trace[1619501438] 'agreement among raft nodes before linearized reading' (duration: 174.520245ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.067137Z","caller":"traceutil/trace.go:171","msg":"trace[1914327959] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"167.214431ms","start":"2026-07-09T16:37:07.899912Z","end":"2026-07-09T16:37:08.067126Z","steps":["trace[1914327959] 'process raft request' (duration: 166.411419ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.067236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.670863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.067262Z","caller":"traceutil/trace.go:171","msg":"trace[1338605546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:10; }","duration":"243.703794ms","start":"2026-07-09T16:37:07.823552Z","end":"2026-07-09T16:37:08.067256Z","steps":["trace[1338605546] 'agreement among raft nodes before linearized reading' (duration: 243.667113ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.067091Z","caller":"traceutil/trace.go:171","msg":"trace[1587165718] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"173.392234ms","start":"2026-07-09T16:37:07.893639Z","end":"2026-07-09T16:37:08.067031Z","steps":["trace[1587165718] 'agreement among raft nodes before linearized reading' (duration: 173.283121ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.067366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.859809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-07-09T16:37:08.067387Z","caller":"traceutil/trace.go:171","msg":"trace[921969369] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:10; }","duration":"243.898249ms","start":"2026-07-09T16:37:07.823483Z","end":"2026-07-09T16:37:08.067381Z","steps":["trace[921969369] 'agreement among raft nodes before linearized reading' (duration: 243.854548ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.06709Z","caller":"traceutil/trace.go:171","msg":"trace[606134582] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"151.049378ms","start":"2026-07-09T16:37:07.915985Z","end":"2026-07-09T16:37:08.067034Z","steps":["trace[606134582] 'process raft request' (duration: 150.381499ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:08.06758Z","caller":"traceutil/trace.go:171","msg":"trace[1770960056] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"147.906079ms","start":"2026-07-09T16:37:07.919658Z","end":"2026-07-09T16:37:08.067564Z","steps":["trace[1770960056] 'process raft request' (duration: 146.732417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.066957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.381623ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.067679Z","caller":"traceutil/trace.go:171","msg":"trace[840696528] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"174.126874ms","start":"2026-07-09T16:37:07.893536Z","end":"2026-07-09T16:37:08.067663Z","steps":["trace[840696528] 'agreement among raft nodes before linearized reading' (duration: 173.397984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:08.067756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.776306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-09T16:37:08.067785Z","caller":"traceutil/trace.go:171","msg":"trace[972230647] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"122.842017ms","start":"2026-07-09T16:37:07.944937Z","end":"2026-07-09T16:37:08.067779Z","steps":["trace[972230647] 'agreement among raft nodes before linearized reading' (duration: 122.802726ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:13.989871Z","caller":"traceutil/trace.go:171","msg":"trace[129093574] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"893.723129ms","start":"2026-07-09T16:37:13.09611Z","end":"2026-07-09T16:37:13.989833Z","steps":["trace[129093574] 'process raft request' (duration: 893.526774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:13.990484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:13.09607Z","time spent":"893.912504ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:14.72825Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.337306ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:14.728438Z","caller":"traceutil/trace.go:171","msg":"trace[1593357893] linearizableReadLoop","detail":"{readStateIndex:296; appliedIndex:295; }","duration":"573.746997ms","start":"2026-07-09T16:37:14.154671Z","end":"2026-07-09T16:37:14.728418Z","steps":["trace[1593357893] 'read index received' (duration: 56.061µs)","trace[1593357893] 'applied index is now lower than readState.Index' (duration: 573.689226ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:14.728499Z","caller":"traceutil/trace.go:171","msg":"trace[155384843] transaction","detail":"{read_only:false; response_revision:284; number_of_response:1; }","duration":"729.715624ms","start":"2026-07-09T16:37:13.998734Z","end":"2026-07-09T16:37:14.72845Z","steps":["trace[155384843] 'process raft request' (duration: 68.835563ms)","trace[155384843] 'compare' (duration: 660.16294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:14.728623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.944713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T16:37:14.728666Z","caller":"traceutil/trace.go:171","msg":"trace[829213168] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:284; }","duration":"574.043855ms","start":"2026-07-09T16:37:14.154608Z","end":"2026-07-09T16:37:14.728652Z","steps":["trace[829213168] 'agreement among raft nodes before linearized reading' (duration: 573.920512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.728712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.154581Z","time spent":"574.121488ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-09T16:37:14.728738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"570.653868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-09T16:37:14.728844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:13.998719Z","time spent":"729.889108ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:14.728894Z","caller":"traceutil/trace.go:171","msg":"trace[490921112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:284; }","duration":"570.854244ms","start":"2026-07-09T16:37:14.157984Z","end":"2026-07-09T16:37:14.728838Z","steps":["trace[490921112] 'agreement among raft nodes before linearized reading' (duration: 570.621178ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.728964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.157953Z","time spent":"570.997119ms","remote":"127.0.0.1:34392","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-09T16:37:14.728987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.014662ms","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-09T16:37:14.729105Z","caller":"traceutil/trace.go:171","msg":"trace[1510556249] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:284; }","duration":"569.182226ms","start":"2026-07-09T16:37:14.159899Z","end":"2026-07-09T16:37:14.729081Z","steps":["trace[1510556249] 'agreement among raft nodes before linearized reading' (duration: 568.9755ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:14.729166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.159877Z","time spent":"569.276669ms","remote":"127.0.0.1:34392","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-07-09T16:37:15.234527Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952155544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:37:15.73563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952155544,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:37:15.746362Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012720348s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-09T16:37:15.858175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.199637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:15.858442Z","caller":"traceutil/trace.go:171","msg":"trace[1361246248] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:296; }","duration":"1.124008068s","start":"2026-07-09T16:37:14.734394Z","end":"2026-07-09T16:37:15.858402Z","steps":["trace[1361246248] 'read index received' (duration: 1.012326517s)","trace[1361246248] 'applied index is now lower than readState.Index' (duration: 111.680411ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:15.858584Z","caller":"traceutil/trace.go:171","msg":"trace[993582731] transaction","detail":"{read_only:false; response_revision:287; number_of_response:1; }","duration":"1.123192655s","start":"2026-07-09T16:37:14.735365Z","end":"2026-07-09T16:37:15.858558Z","steps":["trace[993582731] 'process raft request' (duration: 1.12299603s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:15.858573Z","caller":"traceutil/trace.go:171","msg":"trace[770182392] transaction","detail":"{read_only:false; response_revision:286; number_of_response:1; }","duration":"1.123894465s","start":"2026-07-09T16:37:14.734661Z","end":"2026-07-09T16:37:15.858556Z","steps":["trace[770182392] 'process raft request' (duration: 1.123635838s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:15.858769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.010413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-09T16:37:15.858783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.124378298s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:15.858841Z","caller":"traceutil/trace.go:171","msg":"trace[15957774] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:287; }","duration":"825.119196ms","start":"2026-07-09T16:37:15.033702Z","end":"2026-07-09T16:37:15.858821Z","steps":["trace[15957774] 'agreement among raft nodes before linearized reading' (duration: 824.976322ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:15.858683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.735349Z","time spent":"1.123282607s","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:15.858844Z","caller":"traceutil/trace.go:171","msg":"trace[1940268655] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/persistent-volume-binder; range_end:; response_count:0; response_revision:287; }","duration":"1.124488602s","start":"2026-07-09T16:37:14.73434Z","end":"2026-07-09T16:37:15.858829Z","steps":["trace[1940268655] 'agreement among raft nodes before linearized reading' (duration: 1.124396919s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:15.858889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:15.033679Z","time spent":"825.198247ms","remote":"127.0.0.1:34392","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":"warn","ts":"2026-07-09T16:37:15.858896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.734297Z","time spent":"1.124588224s","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/persistent-volume-binder\" "} {"level":"warn","ts":"2026-07-09T16:37:15.859023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.734626Z","time spent":"1.124138482s","remote":"127.0.0.1:34392","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-09T16:37:15.858413Z","caller":"traceutil/trace.go:171","msg":"trace[774834551] transaction","detail":"{read_only:false; response_revision:285; number_of_response:1; }","duration":"1.124835411s","start":"2026-07-09T16:37:14.733539Z","end":"2026-07-09T16:37:15.858375Z","steps":["trace[774834551] 'process raft request' (duration: 1.01316473s)","trace[774834551] 'compare' (duration: 111.067924ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:15.859337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:14.733516Z","time spent":"1.125690875s","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:28.178176Z","caller":"traceutil/trace.go:171","msg":"trace[1433846116] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"181.716949ms","start":"2026-07-09T16:37:27.996422Z","end":"2026-07-09T16:37:28.178139Z","steps":["trace[1433846116] 'process raft request' (duration: 181.470042ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:28.178826Z","caller":"traceutil/trace.go:171","msg":"trace[495675048] linearizableReadLoop","detail":"{readStateIndex:395; appliedIndex:395; }","duration":"181.934185ms","start":"2026-07-09T16:37:27.99683Z","end":"2026-07-09T16:37:28.178764Z","steps":["trace[495675048] 'read index received' (duration: 181.923745ms)","trace[495675048] 'applied index is now lower than readState.Index' (duration: 8.29µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:28.178991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.134291ms","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-07-09T16:37:28.179086Z","caller":"traceutil/trace.go:171","msg":"trace[1722921003] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:380; }","duration":"182.262715ms","start":"2026-07-09T16:37:27.9968Z","end":"2026-07-09T16:37:28.179063Z","steps":["trace[1722921003] 'agreement among raft nodes before linearized reading' (duration: 182.110671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:28.360663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.591374ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:28.36106Z","caller":"traceutil/trace.go:171","msg":"trace[574999209] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"178.608108ms","start":"2026-07-09T16:37:28.182428Z","end":"2026-07-09T16:37:28.361037Z","steps":["trace[574999209] 'process raft request' (duration: 178.34088ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:28.36107Z","caller":"traceutil/trace.go:171","msg":"trace[643104000] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"362.927453ms","start":"2026-07-09T16:37:27.998119Z","end":"2026-07-09T16:37:28.361046Z","steps":["trace[643104000] 'process raft request' (duration: 223.853355ms)","trace[643104000] 'compare' (duration: 138.338197ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:28.361192Z","caller":"traceutil/trace.go:171","msg":"trace[514525426] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"182.208603ms","start":"2026-07-09T16:37:28.178948Z","end":"2026-07-09T16:37:28.361156Z","steps":["trace[514525426] 'read index received' (duration: 43.039902ms)","trace[514525426] 'applied index is now lower than readState.Index' (duration: 139.164871ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:28.361365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:27.998106Z","time spent":"363.151169ms","remote":"127.0.0.1:34392","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-07-09T16:37:28.361422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.144569ms","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-09T16:37:28.361509Z","caller":"traceutil/trace.go:171","msg":"trace[2031290075] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:382; }","duration":"364.249112ms","start":"2026-07-09T16:37:27.997239Z","end":"2026-07-09T16:37:28.361488Z","steps":["trace[2031290075] 'agreement among raft nodes before linearized reading' (duration: 364.075527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:28.361664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:27.997229Z","time spent":"364.417217ms","remote":"127.0.0.1:34392","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":"info","ts":"2026-07-09T16:37:29.510515Z","caller":"traceutil/trace.go:171","msg":"trace[633981471] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"155.485887ms","start":"2026-07-09T16:37:29.354998Z","end":"2026-07-09T16:37:29.510484Z","steps":["trace[633981471] 'process raft request' (duration: 155.308452ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:29.994751Z","caller":"traceutil/trace.go:171","msg":"trace[826015258] linearizableReadLoop","detail":"{readStateIndex:402; appliedIndex:401; }","duration":"164.07971ms","start":"2026-07-09T16:37:29.830651Z","end":"2026-07-09T16:37:29.994731Z","steps":["trace[826015258] 'read index received' (duration: 130.051861ms)","trace[826015258] 'applied index is now lower than readState.Index' (duration: 34.027159ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:29.994893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.209024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:29.994983Z","caller":"traceutil/trace.go:171","msg":"trace[499022085] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:386; }","duration":"164.318247ms","start":"2026-07-09T16:37:29.830596Z","end":"2026-07-09T16:37:29.994914Z","steps":["trace[499022085] 'agreement among raft nodes before linearized reading' (duration: 164.210544ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:29.995147Z","caller":"traceutil/trace.go:171","msg":"trace[985979378] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"315.122808ms","start":"2026-07-09T16:37:29.679988Z","end":"2026-07-09T16:37:29.99511Z","steps":["trace[985979378] 'process raft request' (duration: 280.748709ms)","trace[985979378] 'compare' (duration: 33.912236ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:29.99529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:29.679976Z","time spent":"315.244142ms","remote":"127.0.0.1:34162","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:44.582812Z","caller":"traceutil/trace.go:171","msg":"trace[498788767] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"320.970431ms","start":"2026-07-09T16:37:44.261813Z","end":"2026-07-09T16:37:44.582783Z","steps":["trace[498788767] 'process raft request' (duration: 320.747635ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:44.582963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.261784Z","time spent":"321.103875ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.362176Z","caller":"traceutil/trace.go:171","msg":"trace[1602597342] linearizableReadLoop","detail":"{readStateIndex:509; appliedIndex:500; }","duration":"904.560182ms","start":"2026-07-09T16:37:44.457593Z","end":"2026-07-09T16:37:45.362153Z","steps":["trace[1602597342] 'read index received' (duration: 124.946477ms)","trace[1602597342] 'applied index is now lower than readState.Index' (duration: 779.612464ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:45.362283Z","caller":"traceutil/trace.go:171","msg":"trace[449434182] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"915.861421ms","start":"2026-07-09T16:37:44.446413Z","end":"2026-07-09T16:37:45.362274Z","steps":["trace[449434182] 'process raft request' (duration: 915.50058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.362365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.446372Z","time spent":"915.936873ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.362435Z","caller":"traceutil/trace.go:171","msg":"trace[573244737] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"925.717947ms","start":"2026-07-09T16:37:44.436695Z","end":"2026-07-09T16:37:45.362413Z","steps":["trace[573244737] 'process raft request' (duration: 924.764319ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.362545Z","caller":"traceutil/trace.go:171","msg":"trace[1266155585] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"912.387567ms","start":"2026-07-09T16:37:44.450148Z","end":"2026-07-09T16:37:45.362536Z","steps":["trace[1266155585] 'process raft request' (duration: 911.80604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.362575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.43669Z","time spent":"925.835521ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.362594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.450114Z","time spent":"912.454209ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.362565Z","caller":"traceutil/trace.go:171","msg":"trace[452295319] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"932.477081ms","start":"2026-07-09T16:37:44.42999Z","end":"2026-07-09T16:37:45.362467Z","steps":["trace[452295319] 'process raft request' (duration: 931.10271ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.362671Z","caller":"traceutil/trace.go:171","msg":"trace[2052018310] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"894.826829ms","start":"2026-07-09T16:37:44.467836Z","end":"2026-07-09T16:37:45.362663Z","steps":["trace[2052018310] 'process raft request' (duration: 894.162629ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.362781Z","caller":"traceutil/trace.go:171","msg":"trace[1484952715] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"926.12267ms","start":"2026-07-09T16:37:44.436646Z","end":"2026-07-09T16:37:45.362768Z","steps":["trace[1484952715] 'process raft request' (duration: 924.755209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.362829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.467821Z","time spent":"894.983033ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.362818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.429966Z","time spent":"932.752169ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.3623Z","caller":"traceutil/trace.go:171","msg":"trace[711260170] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"916.407357ms","start":"2026-07-09T16:37:44.445868Z","end":"2026-07-09T16:37:45.362276Z","steps":["trace[711260170] 'process raft request' (duration: 915.941533ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.36292Z","caller":"traceutil/trace.go:171","msg":"trace[47241095] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"891.328503ms","start":"2026-07-09T16:37:44.471583Z","end":"2026-07-09T16:37:45.362911Z","steps":["trace[47241095] 'process raft request' (duration: 890.469037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.36297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.471566Z","time spent":"891.384385ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.362999Z","caller":"traceutil/trace.go:171","msg":"trace[1628341969] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"863.410534ms","start":"2026-07-09T16:37:44.499554Z","end":"2026-07-09T16:37:45.362965Z","steps":["trace[1628341969] 'process raft request' (duration: 862.550698ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.363013Z","caller":"traceutil/trace.go:171","msg":"trace[1173246789] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"924.093469ms","start":"2026-07-09T16:37:44.438904Z","end":"2026-07-09T16:37:45.362998Z","steps":["trace[1173246789] 'process raft request' (duration: 922.79822ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.363078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.49953Z","time spent":"863.510837ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.363075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.438896Z","time spent":"924.15047ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.363105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"905.476469ms","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-07-09T16:37:45.363194Z","caller":"traceutil/trace.go:171","msg":"trace[1433324608] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"921.632875ms","start":"2026-07-09T16:37:44.44155Z","end":"2026-07-09T16:37:45.363183Z","steps":["trace[1433324608] 'process raft request' (duration: 920.216893ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:45.363215Z","caller":"traceutil/trace.go:171","msg":"trace[407037380] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:493; }","duration":"905.609663ms","start":"2026-07-09T16:37:44.457571Z","end":"2026-07-09T16:37:45.36318Z","steps":["trace[407037380] 'agreement among raft nodes before linearized reading' (duration: 905.341375ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.363278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.457562Z","time spent":"905.702496ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:37:45.3633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.441541Z","time spent":"921.683086ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.363564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.445849Z","time spent":"917.038356ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.363472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.436628Z","time spent":"926.183711ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:45.619018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.203011ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:37:45.61962Z","caller":"traceutil/trace.go:171","msg":"trace[29059288] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"1.018450157s","start":"2026-07-09T16:37:44.601152Z","end":"2026-07-09T16:37:45.619602Z","steps":["trace[29059288] 'process raft request' (duration: 884.598947ms)","trace[29059288] 'compare' (duration: 132.948113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:45.61968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.601124Z","time spent":"1.018534479s","remote":"127.0.0.1:34186","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-09T16:37:45.975937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.823932ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:37:45.976209Z","caller":"traceutil/trace.go:171","msg":"trace[225085364] linearizableReadLoop","detail":"{readStateIndex:514; appliedIndex:512; }","duration":"613.905953ms","start":"2026-07-09T16:37:45.362245Z","end":"2026-07-09T16:37:45.976151Z","steps":["trace[225085364] 'read index received' (duration: 123.344504ms)","trace[225085364] 'applied index is now lower than readState.Index' (duration: 490.554609ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:45.976431Z","caller":"traceutil/trace.go:171","msg":"trace[1603826630] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"608.045297ms","start":"2026-07-09T16:37:45.368367Z","end":"2026-07-09T16:37:45.976412Z","steps":["trace[1603826630] 'process raft request' (duration: 607.663986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.976541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.368336Z","time spent":"608.14545ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:45.976765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.146933533s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:45.979083Z","caller":"traceutil/trace.go:171","msg":"trace[310348756] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"1.149253003s","start":"2026-07-09T16:37:44.829789Z","end":"2026-07-09T16:37:45.979042Z","steps":["trace[310348756] 'agreement among raft nodes before linearized reading' (duration: 1.146629374s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.97916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.829775Z","time spent":"1.149362697s","remote":"127.0.0.1:34122","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-09T16:37:45.97672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.487102792s","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-09T16:37:45.97931Z","caller":"traceutil/trace.go:171","msg":"trace[1414852604] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"1.489745181s","start":"2026-07-09T16:37:44.489557Z","end":"2026-07-09T16:37:45.979302Z","steps":["trace[1414852604] 'agreement among raft nodes before linearized reading' (duration: 1.486842774s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.979336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.489542Z","time spent":"1.489786753s","remote":"127.0.0.1:34392","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-09T16:37:45.976886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439724368s","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-09T16:37:45.979561Z","caller":"traceutil/trace.go:171","msg":"trace[573756242] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:496; }","duration":"1.442433799s","start":"2026-07-09T16:37:44.537116Z","end":"2026-07-09T16:37:45.97955Z","steps":["trace[573756242] 'agreement among raft nodes before linearized reading' (duration: 1.439311756s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.979596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:44.537103Z","time spent":"1.44248258s","remote":"127.0.0.1:34392","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":"info","ts":"2026-07-09T16:37:45.976898Z","caller":"traceutil/trace.go:171","msg":"trace[917860814] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"601.843809ms","start":"2026-07-09T16:37:45.374979Z","end":"2026-07-09T16:37:45.976823Z","steps":["trace[917860814] 'process raft request' (duration: 601.176069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.979699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.37497Z","time spent":"604.693625ms","remote":"127.0.0.1:34644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.976993Z","caller":"traceutil/trace.go:171","msg":"trace[1374605989] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"597.951172ms","start":"2026-07-09T16:37:45.379029Z","end":"2026-07-09T16:37:45.97698Z","steps":["trace[1374605989] 'process raft request' (duration: 597.511789ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.979823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.379018Z","time spent":"600.787278ms","remote":"127.0.0.1:34644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.97712Z","caller":"traceutil/trace.go:171","msg":"trace[1441552094] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"597.036265ms","start":"2026-07-09T16:37:45.38007Z","end":"2026-07-09T16:37:45.977106Z","steps":["trace[1441552094] 'process raft request' (duration: 596.927971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.979902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.380061Z","time spent":"599.827179ms","remote":"127.0.0.1:34644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.977159Z","caller":"traceutil/trace.go:171","msg":"trace[490717390] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"597.623774ms","start":"2026-07-09T16:37:45.379528Z","end":"2026-07-09T16:37:45.977151Z","steps":["trace[490717390] 'process raft request' (duration: 597.415897ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.379506Z","time spent":"600.466239ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.977474Z","caller":"traceutil/trace.go:171","msg":"trace[620770425] transaction","detail":"{read_only:false; number_of_response:1; response_revision:500; }","duration":"594.148308ms","start":"2026-07-09T16:37:45.383294Z","end":"2026-07-09T16:37:45.977443Z","steps":["trace[620770425] 'process raft request' (duration: 594.006063ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.383278Z","time spent":"596.829769ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6937,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.977472Z","caller":"traceutil/trace.go:171","msg":"trace[648018903] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"595.499569ms","start":"2026-07-09T16:37:45.381959Z","end":"2026-07-09T16:37:45.977459Z","steps":["trace[648018903] 'process raft request' (duration: 595.080206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.381951Z","time spent":"598.280062ms","remote":"127.0.0.1:34644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.977549Z","caller":"traceutil/trace.go:171","msg":"trace[645108924] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"593.733976ms","start":"2026-07-09T16:37:45.383806Z","end":"2026-07-09T16:37:45.97754Z","steps":["trace[645108924] 'process raft request' (duration: 593.54107ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.383799Z","time spent":"596.610282ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.977647Z","caller":"traceutil/trace.go:171","msg":"trace[1700434828] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"585.605551ms","start":"2026-07-09T16:37:45.392031Z","end":"2026-07-09T16:37:45.977636Z","steps":["trace[1700434828] 'process raft request' (duration: 585.480018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.39202Z","time spent":"588.595591ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.977667Z","caller":"traceutil/trace.go:171","msg":"trace[1740830482] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"593.512339ms","start":"2026-07-09T16:37:45.384146Z","end":"2026-07-09T16:37:45.977658Z","steps":["trace[1740830482] 'process raft request' (duration: 593.272802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.38414Z","time spent":"596.614672ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:45.977686Z","caller":"traceutil/trace.go:171","msg":"trace[1987706794] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"590.431837ms","start":"2026-07-09T16:37:45.387244Z","end":"2026-07-09T16:37:45.977675Z","steps":["trace[1987706794] 'process raft request' (duration: 590.232221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.980962Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.387235Z","time spent":"593.661853ms","remote":"127.0.0.1:34644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:45.97809Z","caller":"traceutil/trace.go:171","msg":"trace[1060802235] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"585.739224ms","start":"2026-07-09T16:37:45.392336Z","end":"2026-07-09T16:37:45.978075Z","steps":["trace[1060802235] 'process raft request' (duration: 585.2391ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:45.981171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.392316Z","time spent":"588.809477ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:46.273947Z","caller":"traceutil/trace.go:171","msg":"trace[1163942440] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:526; }","duration":"293.420424ms","start":"2026-07-09T16:37:45.980501Z","end":"2026-07-09T16:37:46.273922Z","steps":["trace[1163942440] 'read index received' (duration: 254.703137ms)","trace[1163942440] 'applied index is now lower than readState.Index' (duration: 38.716627ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:46.274095Z","caller":"traceutil/trace.go:171","msg":"trace[643396126] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"601.345745ms","start":"2026-07-09T16:37:45.672711Z","end":"2026-07-09T16:37:46.274057Z","steps":["trace[643396126] 'process raft request' (duration: 562.409442ms)","trace[643396126] 'compare' (duration: 38.681145ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:46.274172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.655873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:46.274204Z","caller":"traceutil/trace.go:171","msg":"trace[1808998892] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:506; }","duration":"293.741925ms","start":"2026-07-09T16:37:45.980454Z","end":"2026-07-09T16:37:46.274196Z","steps":["trace[1808998892] 'agreement among raft nodes before linearized reading' (duration: 293.545898ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:46.274328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:45.672676Z","time spent":"601.50709ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:46.274545Z","caller":"traceutil/trace.go:171","msg":"trace[1540506215] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"289.663042ms","start":"2026-07-09T16:37:45.98485Z","end":"2026-07-09T16:37:46.274513Z","steps":["trace[1540506215] 'process raft request' (duration: 289.229848ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.275393Z","caller":"traceutil/trace.go:171","msg":"trace[747554050] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"290.457256ms","start":"2026-07-09T16:37:45.98492Z","end":"2026-07-09T16:37:46.275378Z","steps":["trace[747554050] 'process raft request' (duration: 289.458036ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.276203Z","caller":"traceutil/trace.go:171","msg":"trace[2075178472] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"279.161785ms","start":"2026-07-09T16:37:45.996994Z","end":"2026-07-09T16:37:46.276156Z","steps":["trace[2075178472] 'process raft request' (duration: 278.213807ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.276462Z","caller":"traceutil/trace.go:171","msg":"trace[742242956] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"262.713189ms","start":"2026-07-09T16:37:46.013736Z","end":"2026-07-09T16:37:46.276449Z","steps":["trace[742242956] 'process raft request' (duration: 262.227605ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.278858Z","caller":"traceutil/trace.go:171","msg":"trace[686575779] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"223.90933ms","start":"2026-07-09T16:37:46.054928Z","end":"2026-07-09T16:37:46.278838Z","steps":["trace[686575779] 'process raft request' (duration: 223.812617ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.27919Z","caller":"traceutil/trace.go:171","msg":"trace[750031650] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"257.837703ms","start":"2026-07-09T16:37:46.021298Z","end":"2026-07-09T16:37:46.279136Z","steps":["trace[750031650] 'process raft request' (duration: 257.320628ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:46.279449Z","caller":"traceutil/trace.go:171","msg":"trace[510058567] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"258.363589ms","start":"2026-07-09T16:37:46.021072Z","end":"2026-07-09T16:37:46.279436Z","steps":["trace[510058567] 'process raft request' (duration: 255.057059ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:51.637784Z","caller":"traceutil/trace.go:171","msg":"trace[1703174449] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"180.66192ms","start":"2026-07-09T16:37:51.457096Z","end":"2026-07-09T16:37:51.637757Z","steps":["trace[1703174449] 'process raft request' (duration: 180.346231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.043828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.539298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:52.043935Z","caller":"traceutil/trace.go:171","msg":"trace[1335973520] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:575; }","duration":"328.690452ms","start":"2026-07-09T16:37:51.715221Z","end":"2026-07-09T16:37:52.043911Z","steps":["trace[1335973520] 'range keys from in-memory index tree' (duration: 328.454875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.044001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:51.71519Z","time spent":"328.782445ms","remote":"127.0.0.1:34334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-07-09T16:37:52.697734Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952156174,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:37:52.825001Z","caller":"traceutil/trace.go:171","msg":"trace[1565339016] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"774.661658ms","start":"2026-07-09T16:37:52.050311Z","end":"2026-07-09T16:37:52.824973Z","steps":["trace[1565339016] 'process raft request' (duration: 774.468702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.825164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.050277Z","time spent":"774.804063ms","remote":"127.0.0.1:34392","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-09T16:37:52.921394Z","caller":"traceutil/trace.go:171","msg":"trace[1474717554] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"560.527315ms","start":"2026-07-09T16:37:52.360839Z","end":"2026-07-09T16:37:52.921367Z","steps":["trace[1474717554] 'process raft request' (duration: 552.644016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.921549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.360815Z","time spent":"560.653358ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:37:52.922244Z","caller":"traceutil/trace.go:171","msg":"trace[1605831689] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:597; }","duration":"725.189975ms","start":"2026-07-09T16:37:52.197032Z","end":"2026-07-09T16:37:52.922222Z","steps":["trace[1605831689] 'read index received' (duration: 627.830178ms)","trace[1605831689] 'applied index is now lower than readState.Index' (duration: 97.358957ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:52.922354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.314749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:52.922394Z","caller":"traceutil/trace.go:171","msg":"trace[1279776371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:578; }","duration":"725.395471ms","start":"2026-07-09T16:37:52.196986Z","end":"2026-07-09T16:37:52.922382Z","steps":["trace[1279776371] 'agreement among raft nodes before linearized reading' (duration: 725.326959ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:52.922342Z","caller":"traceutil/trace.go:171","msg":"trace[630744338] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"453.883645ms","start":"2026-07-09T16:37:52.468424Z","end":"2026-07-09T16:37:52.922308Z","steps":["trace[630744338] 'process raft request' (duration: 453.136821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.92242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.196966Z","time spent":"725.447703ms","remote":"127.0.0.1:34122","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-09T16:37:52.922562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.468402Z","time spent":"454.05488ms","remote":"127.0.0.1:34224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:52.922911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.67354ms","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-09T16:37:52.922987Z","caller":"traceutil/trace.go:171","msg":"trace[590217631] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"618.806245ms","start":"2026-07-09T16:37:52.304163Z","end":"2026-07-09T16:37:52.922969Z","steps":["trace[590217631] 'agreement among raft nodes before linearized reading' (duration: 618.670671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.923054Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.304134Z","time spent":"618.910638ms","remote":"127.0.0.1:34392","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-07-09T16:37:52.923102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.888492ms","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-09T16:37:52.923182Z","caller":"traceutil/trace.go:171","msg":"trace[100258776] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:578; }","duration":"604.007115ms","start":"2026-07-09T16:37:52.319152Z","end":"2026-07-09T16:37:52.923159Z","steps":["trace[100258776] 'agreement among raft nodes before linearized reading' (duration: 603.868221ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:52.923257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:52.319128Z","time spent":"604.096428ms","remote":"127.0.0.1:34392","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-09T16:37:52.923553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.546326ms","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-07-09T16:37:52.923901Z","caller":"traceutil/trace.go:171","msg":"trace[2000025162] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:578; }","duration":"294.69834ms","start":"2026-07-09T16:37:52.628955Z","end":"2026-07-09T16:37:52.923653Z","steps":["trace[2000025162] 'agreement among raft nodes before linearized reading' (duration: 293.990549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:53.250284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.07721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:53.250463Z","caller":"traceutil/trace.go:171","msg":"trace[733884517] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"190.903635ms","start":"2026-07-09T16:37:53.059531Z","end":"2026-07-09T16:37:53.250435Z","steps":["trace[733884517] 'process raft request' (duration: 133.968703ms)","trace[733884517] 'compare' (duration: 56.611952ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:53.250475Z","caller":"traceutil/trace.go:171","msg":"trace[870212711] range","detail":"{range_begin:/registry/roles/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:582; }","duration":"215.299157ms","start":"2026-07-09T16:37:53.035152Z","end":"2026-07-09T16:37:53.250451Z","steps":["trace[870212711] 'range keys from in-memory index tree' (duration: 215.015319ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:53.846917Z","caller":"traceutil/trace.go:171","msg":"trace[370836138] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"183.325375ms","start":"2026-07-09T16:37:53.663562Z","end":"2026-07-09T16:37:53.846888Z","steps":["trace[370836138] 'process raft request' (duration: 179.394575ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:54.045201Z","caller":"traceutil/trace.go:171","msg":"trace[251844640] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"341.917279ms","start":"2026-07-09T16:37:53.703249Z","end":"2026-07-09T16:37:54.045167Z","steps":["trace[251844640] 'process raft request' (duration: 259.182442ms)","trace[251844640] 'compare' (duration: 82.555161ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:54.045315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:53.703224Z","time spent":"342.040262ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":688,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:37:54.045333Z","caller":"traceutil/trace.go:171","msg":"trace[1407522464] linearizableReadLoop","detail":"{readStateIndex:607; appliedIndex:605; }","duration":"265.214196ms","start":"2026-07-09T16:37:53.780042Z","end":"2026-07-09T16:37:54.045256Z","steps":["trace[1407522464] 'read index received' (duration: 60.879131ms)","trace[1407522464] 'applied index is now lower than readState.Index' (duration: 204.333315ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:54.045202Z","caller":"traceutil/trace.go:171","msg":"trace[23085512] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"145.328861ms","start":"2026-07-09T16:37:53.899856Z","end":"2026-07-09T16:37:54.045185Z","steps":["trace[23085512] 'process raft request' (duration: 145.278718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:54.045713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.65895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-09T16:37:54.045745Z","caller":"traceutil/trace.go:171","msg":"trace[502043615] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:586; }","duration":"265.737462ms","start":"2026-07-09T16:37:53.78Z","end":"2026-07-09T16:37:54.045737Z","steps":["trace[502043615] 'agreement among raft nodes before linearized reading' (duration: 265.632289ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:54.04573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.288759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:54.045788Z","caller":"traceutil/trace.go:171","msg":"trace[1127772148] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:586; }","duration":"218.386122ms","start":"2026-07-09T16:37:53.827381Z","end":"2026-07-09T16:37:54.045767Z","steps":["trace[1127772148] 'agreement among raft nodes before linearized reading' (duration: 218.238278ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:54.222479Z","caller":"traceutil/trace.go:171","msg":"trace[1764507899] linearizableReadLoop","detail":"{readStateIndex:609; appliedIndex:608; }","duration":"169.15702ms","start":"2026-07-09T16:37:54.053296Z","end":"2026-07-09T16:37:54.222453Z","steps":["trace[1764507899] 'read index received' (duration: 93.693533ms)","trace[1764507899] 'applied index is now lower than readState.Index' (duration: 75.462467ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:54.222543Z","caller":"traceutil/trace.go:171","msg":"trace[1848040094] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"172.820752ms","start":"2026-07-09T16:37:54.04969Z","end":"2026-07-09T16:37:54.22251Z","steps":["trace[1848040094] 'process raft request' (duration: 97.372525ms)","trace[1848040094] 'compare' (duration: 75.253771ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:54.222813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.4974ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/cilium-config\" ","response":"range_response_count:1 size:6531"} {"level":"info","ts":"2026-07-09T16:37:54.222861Z","caller":"traceutil/trace.go:171","msg":"trace[1466641709] range","detail":"{range_begin:/registry/configmaps/kube-system/cilium-config; range_end:; response_count:1; response_revision:587; }","duration":"169.773409ms","start":"2026-07-09T16:37:54.053074Z","end":"2026-07-09T16:37:54.222847Z","steps":["trace[1466641709] 'agreement among raft nodes before linearized reading' (duration: 169.509571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:54.223059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.703284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:54.223204Z","caller":"traceutil/trace.go:171","msg":"trace[784218501] range","detail":"{range_begin:/registry/rolebindings/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:587; }","duration":"165.946962ms","start":"2026-07-09T16:37:54.057235Z","end":"2026-07-09T16:37:54.223182Z","steps":["trace[784218501] 'agreement among raft nodes before linearized reading' (duration: 165.56115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:55.330581Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952156195,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:37:55.43895Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.141044411s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:37:55.439712Z","caller":"traceutil/trace.go:171","msg":"trace[955434362] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"1.141884197s","start":"2026-07-09T16:37:54.297787Z","end":"2026-07-09T16:37:55.439671Z","steps":["trace[955434362] 'process raft request' (duration: 1.141372342s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:55.440222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.297774Z","time spent":"1.14232333s","remote":"127.0.0.1:34316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:56.639292Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198776497s","expected-duration":"1s"} {"level":"info","ts":"2026-07-09T16:37:56.64167Z","caller":"traceutil/trace.go:171","msg":"trace[2129902871] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"2.013951468s","start":"2026-07-09T16:37:54.627686Z","end":"2026-07-09T16:37:56.641637Z","steps":["trace[2129902871] 'process raft request' (duration: 2.012507544s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:56.64183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.627659Z","time spent":"2.014075872s","remote":"127.0.0.1:34224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036201,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:37:56.830326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000257825s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-09T16:37:56.830412Z","caller":"traceutil/trace.go:171","msg":"trace[281029840] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000387788s","start":"2026-07-09T16:37:54.830006Z","end":"2026-07-09T16:37:56.830394Z","steps":["trace[281029840] 'agreement among raft nodes before linearized reading' (duration: 2.000279554s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:56.830459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.829991Z","time spent":"2.00045857s","remote":"127.0.0.1:34122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/09 16:37:56 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-09T16:37:58.764197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.122595947s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-09T16:37:58.764449Z","caller":"traceutil/trace.go:171","msg":"trace[216331494] linearizableReadLoop","detail":"{readStateIndex:612; appliedIndex:609; }","duration":"3.934386079s","start":"2026-07-09T16:37:54.830044Z","end":"2026-07-09T16:37:58.76443Z","steps":["trace[216331494] 'read index received' (duration: 609.18879ms)","trace[216331494] 'applied index is now lower than readState.Index' (duration: 3.325195919s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:58.764888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.114625373s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:58.765008Z","caller":"traceutil/trace.go:171","msg":"trace[374033300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:589; }","duration":"2.114756547s","start":"2026-07-09T16:37:56.650234Z","end":"2026-07-09T16:37:58.76499Z","steps":["trace[374033300] 'range keys from in-memory index tree' (duration: 2.114609833s)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:58.765106Z","caller":"traceutil/trace.go:171","msg":"trace[231076681] transaction","detail":"{read_only:false; response_revision:590; number_of_response:1; }","duration":"2.066047631s","start":"2026-07-09T16:37:56.699024Z","end":"2026-07-09T16:37:58.765071Z","steps":["trace[231076681] 'process raft request' (duration: 2.065292618s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:58.765247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:56.698984Z","time spent":"2.066187065s","remote":"127.0.0.1:34464","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1044,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:59.338296Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952156202,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:37:59.520521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.689596892s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:37:59.520628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.737585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:37:59.520666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.584951794s","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-07-09T16:37:59.520661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.581729804s","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-09T16:37:59.520695Z","caller":"traceutil/trace.go:171","msg":"trace[877569969] range","detail":"{range_begin:/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system; range_end:; response_count:0; response_revision:590; }","duration":"737.843407ms","start":"2026-07-09T16:37:58.782835Z","end":"2026-07-09T16:37:59.520678Z","steps":["trace[877569969] 'range keys from in-memory index tree' (duration: 737.645231ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:37:59.520704Z","caller":"traceutil/trace.go:171","msg":"trace[52224412] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:590; }","duration":"2.689890422s","start":"2026-07-09T16:37:56.830781Z","end":"2026-07-09T16:37:59.520671Z","steps":["trace[52224412] 'agreement among raft nodes before linearized reading' (duration: 1.934337352s)","trace[52224412] 'range keys from in-memory index tree' (duration: 755.333733ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.520737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:58.782813Z","time spent":"737.91624ms","remote":"127.0.0.1:34466","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/envoy-gateway-gateway-helm-certgen:envoy-gateway-system\" "} {"level":"info","ts":"2026-07-09T16:37:59.520737Z","caller":"traceutil/trace.go:171","msg":"trace[533064386] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:590; }","duration":"4.585054637s","start":"2026-07-09T16:37:54.935655Z","end":"2026-07-09T16:37:59.52071Z","steps":["trace[533064386] 'agreement among raft nodes before linearized reading' (duration: 3.829461936s)","trace[533064386] 'range keys from in-memory index tree' (duration: 755.384455ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:37:59.520743Z","caller":"traceutil/trace.go:171","msg":"trace[1551737571] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:590; }","duration":"4.581848048s","start":"2026-07-09T16:37:54.938871Z","end":"2026-07-09T16:37:59.52072Z","steps":["trace[1551737571] 'agreement among raft nodes before linearized reading' (duration: 3.826228567s)","trace[1551737571] 'range keys from in-memory index tree' (duration: 755.385155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.520786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.935634Z","time spent":"4.585143469s","remote":"127.0.0.1:34392","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-07-09T16:37:59.520792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.938861Z","time spent":"4.58192117s","remote":"127.0.0.1:34392","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-09T16:37:59.520777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:56.830759Z","time spent":"2.690001115s","remote":"127.0.0.1:34132","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-09T16:37:59.520907Z","caller":"traceutil/trace.go:171","msg":"trace[1788437147] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"686.234969ms","start":"2026-07-09T16:37:58.834649Z","end":"2026-07-09T16:37:59.520884Z","steps":["trace[1788437147] 'process raft request' (duration: 596.339348ms)","trace[1788437147] 'compare' (duration: 89.089447ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.520894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.582740646s","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-07-09T16:37:59.521027Z","caller":"traceutil/trace.go:171","msg":"trace[321916182] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:590; }","duration":"4.582924542s","start":"2026-07-09T16:37:54.938068Z","end":"2026-07-09T16:37:59.520992Z","steps":["trace[321916182] 'agreement among raft nodes before linearized reading' (duration: 3.827038612s)","trace[321916182] 'range keys from in-memory index tree' (duration: 755.56492ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.521013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:58.834608Z","time spent":"686.340123ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:37:59.521088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.938045Z","time spent":"4.583031305s","remote":"127.0.0.1:34392","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":"warn","ts":"2026-07-09T16:37:59.520591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.688021471s","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-09T16:37:59.521705Z","caller":"traceutil/trace.go:171","msg":"trace[1415857688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:590; }","duration":"4.689149235s","start":"2026-07-09T16:37:54.832539Z","end":"2026-07-09T16:37:59.521688Z","steps":["trace[1415857688] 'agreement among raft nodes before linearized reading' (duration: 3.932540223s)","trace[1415857688] 'range keys from in-memory index tree' (duration: 755.360514ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.521742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:54.83253Z","time spent":"4.689200346s","remote":"127.0.0.1:34392","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-09T16:37:59.52345Z","caller":"traceutil/trace.go:171","msg":"trace[1158995552] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"685.223289ms","start":"2026-07-09T16:37:58.838204Z","end":"2026-07-09T16:37:59.523427Z","steps":["trace[1158995552] 'read index received' (duration: 592.80529ms)","trace[1158995552] 'applied index is now lower than readState.Index' (duration: 92.416469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:37:59.523614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.408524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:37:59.523658Z","caller":"traceutil/trace.go:171","msg":"trace[725424072] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:592; }","duration":"685.502688ms","start":"2026-07-09T16:37:58.838143Z","end":"2026-07-09T16:37:59.523646Z","steps":["trace[725424072] 'agreement among raft nodes before linearized reading' (duration: 685.374294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:37:59.52371Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:37:58.838118Z","time spent":"685.58495ms","remote":"127.0.0.1:34122","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-09T16:37:59.523703Z","caller":"traceutil/trace.go:171","msg":"trace[766978048] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"281.454375ms","start":"2026-07-09T16:37:59.242227Z","end":"2026-07-09T16:37:59.523681Z","steps":["trace[766978048] 'process raft request' (duration: 281.061043ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:00.66714Z","caller":"traceutil/trace.go:171","msg":"trace[833532004] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"106.113291ms","start":"2026-07-09T16:38:00.560973Z","end":"2026-07-09T16:38:00.667087Z","steps":["trace[833532004] 'compare' (duration: 96.989172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:01.599638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.1484ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:38:01.59984Z","caller":"traceutil/trace.go:171","msg":"trace[2099435728] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"290.573772ms","start":"2026-07-09T16:38:01.309246Z","end":"2026-07-09T16:38:01.59982Z","steps":["trace[2099435728] 'process raft request' (duration: 290.50971ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:01.599923Z","caller":"traceutil/trace.go:171","msg":"trace[1245643556] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"379.342815ms","start":"2026-07-09T16:38:01.220552Z","end":"2026-07-09T16:38:01.599895Z","steps":["trace[1245643556] 'process raft request' (duration: 58.863406ms)","trace[1245643556] 'compare' (duration: 319.987105ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:01.600033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:01.22053Z","time spent":"379.465699ms","remote":"127.0.0.1:34628","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:38:01.599995Z","caller":"traceutil/trace.go:171","msg":"trace[1095817180] linearizableReadLoop","detail":"{readStateIndex:631; appliedIndex:630; }","duration":"293.059649ms","start":"2026-07-09T16:38:01.306904Z","end":"2026-07-09T16:38:01.599964Z","steps":["trace[1095817180] 'read index received' (duration: 45.612µs)","trace[1095817180] 'applied index is now lower than readState.Index' (duration: 293.011686ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:01.600339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.425119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfzf8\" ","response":"range_response_count:1 size:17247"} {"level":"info","ts":"2026-07-09T16:38:01.600489Z","caller":"traceutil/trace.go:171","msg":"trace[306179532] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfzf8; range_end:; response_count:1; response_revision:608; }","duration":"293.620556ms","start":"2026-07-09T16:38:01.306845Z","end":"2026-07-09T16:38:01.600465Z","steps":["trace[306179532] 'agreement among raft nodes before linearized reading' (duration: 293.208213ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:02.444073Z","caller":"traceutil/trace.go:171","msg":"trace[2058136824] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"509.636175ms","start":"2026-07-09T16:38:01.934413Z","end":"2026-07-09T16:38:02.44405Z","steps":["trace[2058136824] 'process raft request' (duration: 509.45794ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:02.444208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:01.934393Z","time spent":"509.755999ms","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:38:02.581231Z","caller":"traceutil/trace.go:171","msg":"trace[40787534] linearizableReadLoop","detail":"{readStateIndex:640; appliedIndex:638; }","duration":"260.212ms","start":"2026-07-09T16:38:02.320992Z","end":"2026-07-09T16:38:02.581204Z","steps":["trace[40787534] 'read index received' (duration: 122.963785ms)","trace[40787534] 'applied index is now lower than readState.Index' (duration: 137.246775ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:02.581291Z","caller":"traceutil/trace.go:171","msg":"trace[189734475] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"512.513846ms","start":"2026-07-09T16:38:02.068751Z","end":"2026-07-09T16:38:02.581265Z","steps":["trace[189734475] 'process raft request' (duration: 511.778483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:02.581409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:02.068719Z","time spent":"512.62393ms","remote":"127.0.0.1:34224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036321,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:02.581485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.448987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfzf8\" ","response":"range_response_count:1 size:17524"} {"level":"info","ts":"2026-07-09T16:38:02.581582Z","caller":"traceutil/trace.go:171","msg":"trace[384977631] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfzf8; range_end:; response_count:1; response_revision:616; }","duration":"260.57478ms","start":"2026-07-09T16:38:02.320988Z","end":"2026-07-09T16:38:02.581563Z","steps":["trace[384977631] 'agreement among raft nodes before linearized reading' (duration: 260.342683ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:03.593967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.28385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:38:03.5941Z","caller":"traceutil/trace.go:171","msg":"trace[1143446115] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"169.432077ms","start":"2026-07-09T16:38:03.424643Z","end":"2026-07-09T16:38:03.594075Z","steps":["trace[1143446115] 'process raft request' (duration: 43.731504ms)","trace[1143446115] 'compare' (duration: 125.112675ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:03.815607Z","caller":"traceutil/trace.go:171","msg":"trace[1996248839] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"323.215043ms","start":"2026-07-09T16:38:03.492355Z","end":"2026-07-09T16:38:03.81557Z","steps":["trace[1996248839] 'process raft request' (duration: 241.489111ms)","trace[1996248839] 'compare' (duration: 81.570268ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:03.815774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.492332Z","time spent":"323.371958ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:38:03.850143Z","caller":"traceutil/trace.go:171","msg":"trace[1715765058] linearizableReadLoop","detail":"{readStateIndex:673; appliedIndex:671; }","duration":"202.042541ms","start":"2026-07-09T16:38:03.64808Z","end":"2026-07-09T16:38:03.850122Z","steps":["trace[1715765058] 'read index received' (duration: 85.775187ms)","trace[1715765058] 'applied index is now lower than readState.Index' (duration: 116.266194ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:03.850588Z","caller":"traceutil/trace.go:171","msg":"trace[740051331] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"250.296503ms","start":"2026-07-09T16:38:03.600206Z","end":"2026-07-09T16:38:03.850503Z","steps":["trace[740051331] 'process raft request' (duration: 249.718775ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:03.850643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.545456ms","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-09T16:38:03.850804Z","caller":"traceutil/trace.go:171","msg":"trace[1437404369] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:650; }","duration":"202.749002ms","start":"2026-07-09T16:38:03.648036Z","end":"2026-07-09T16:38:03.850785Z","steps":["trace[1437404369] 'agreement among raft nodes before linearized reading' (duration: 202.184135ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:03.85113Z","caller":"traceutil/trace.go:171","msg":"trace[1870496628] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"159.11236ms","start":"2026-07-09T16:38:03.691971Z","end":"2026-07-09T16:38:03.851084Z","steps":["trace[1870496628] 'process raft request' (duration: 158.10919ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.339725Z","caller":"traceutil/trace.go:171","msg":"trace[2072226522] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"518.242368ms","start":"2026-07-09T16:38:03.821459Z","end":"2026-07-09T16:38:04.339701Z","steps":["trace[2072226522] 'process raft request' (duration: 518.104084ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.339803Z","caller":"traceutil/trace.go:171","msg":"trace[394447847] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:675; }","duration":"489.546925ms","start":"2026-07-09T16:38:03.850198Z","end":"2026-07-09T16:38:04.339745Z","steps":["trace[394447847] 'read index received' (duration: 489.523085ms)","trace[394447847] 'applied index is now lower than readState.Index' (duration: 19.11µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:04.339912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.821434Z","time spent":"518.370421ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4059,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:04.34018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.29846ms","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-07-09T16:38:04.340233Z","caller":"traceutil/trace.go:171","msg":"trace[770154234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"656.406194ms","start":"2026-07-09T16:38:03.683811Z","end":"2026-07-09T16:38:04.340217Z","steps":["trace[770154234] 'agreement among raft nodes before linearized reading' (duration: 656.138716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.34026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.093785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-09T16:38:04.340322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.683798Z","time spent":"656.508987ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-09T16:38:04.340394Z","caller":"traceutil/trace.go:171","msg":"trace[1967605317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"656.275251ms","start":"2026-07-09T16:38:03.684075Z","end":"2026-07-09T16:38:04.34035Z","steps":["trace[1967605317] 'agreement among raft nodes before linearized reading' (duration: 655.848537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.340469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.684057Z","time spent":"656.400215ms","remote":"127.0.0.1:34392","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":"warn","ts":"2026-07-09T16:38:04.340624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.484776ms","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-09T16:38:04.340769Z","caller":"traceutil/trace.go:171","msg":"trace[835429015] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"656.049674ms","start":"2026-07-09T16:38:03.684694Z","end":"2026-07-09T16:38:04.340744Z","steps":["trace[835429015] 'agreement among raft nodes before linearized reading' (duration: 655.070633ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.341217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.684689Z","time spent":"656.509968ms","remote":"127.0.0.1:34392","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-09T16:38:04.390723Z","caller":"traceutil/trace.go:171","msg":"trace[99226289] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"535.82534ms","start":"2026-07-09T16:38:03.854812Z","end":"2026-07-09T16:38:04.390637Z","steps":["trace[99226289] 'process raft request' (duration: 535.615923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.390904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.854783Z","time spent":"536.012377ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:04.390957Z","caller":"traceutil/trace.go:171","msg":"trace[1863628751] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"533.072066ms","start":"2026-07-09T16:38:03.857872Z","end":"2026-07-09T16:38:04.390944Z","steps":["trace[1863628751] 'process raft request' (duration: 532.703494ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.391017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.857863Z","time spent":"533.123667ms","remote":"127.0.0.1:34570","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4694,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:04.391144Z","caller":"traceutil/trace.go:171","msg":"trace[403884020] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:656; }","duration":"100.012497ms","start":"2026-07-09T16:38:04.291111Z","end":"2026-07-09T16:38:04.391124Z","steps":["trace[403884020] 'agreement among raft nodes before linearized reading' (duration: 99.983936ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.391234Z","caller":"traceutil/trace.go:171","msg":"trace[1087493016] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"298.512694ms","start":"2026-07-09T16:38:04.09271Z","end":"2026-07-09T16:38:04.391223Z","steps":["trace[1087493016] 'process raft request' (duration: 298.248256ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.391319Z","caller":"traceutil/trace.go:171","msg":"trace[940010518] transaction","detail":"{read_only:false; response_revision:654; number_of_response:1; }","duration":"498.171722ms","start":"2026-07-09T16:38:03.89314Z","end":"2026-07-09T16:38:04.391311Z","steps":["trace[940010518] 'process raft request' (duration: 497.619856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.391355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:03.89311Z","time spent":"498.223444ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:38:04.391461Z","caller":"traceutil/trace.go:171","msg":"trace[1030773164] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"260.235056ms","start":"2026-07-09T16:38:04.131217Z","end":"2026-07-09T16:38:04.391452Z","steps":["trace[1030773164] 'process raft request' (duration: 259.818953ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.649426Z","caller":"traceutil/trace.go:171","msg":"trace[679021476] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"303.871109ms","start":"2026-07-09T16:38:04.345521Z","end":"2026-07-09T16:38:04.649393Z","steps":["trace[679021476] 'process raft request' (duration: 303.788247ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.649441Z","caller":"traceutil/trace.go:171","msg":"trace[934276672] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"304.056794ms","start":"2026-07-09T16:38:04.34534Z","end":"2026-07-09T16:38:04.649397Z","steps":["trace[934276672] 'process raft request' (duration: 303.887669ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.649641Z","caller":"traceutil/trace.go:171","msg":"trace[1605463811] transaction","detail":"{read_only:false; response_revision:661; number_of_response:1; }","duration":"303.223178ms","start":"2026-07-09T16:38:04.3464Z","end":"2026-07-09T16:38:04.649623Z","steps":["trace[1605463811] 'process raft request' (duration: 303.142046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.649687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:04.345311Z","time spent":"304.23588ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:04.649744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:04.346391Z","time spent":"303.29994ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4068,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:04.649752Z","caller":"traceutil/trace.go:171","msg":"trace[1094185134] transaction","detail":"{read_only:false; response_revision:660; number_of_response:1; }","duration":"304.192109ms","start":"2026-07-09T16:38:04.345544Z","end":"2026-07-09T16:38:04.649736Z","steps":["trace[1094185134] 'process raft request' (duration: 303.808197ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:04.649833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:04.345523Z","time spent":"304.268901ms","remote":"127.0.0.1:34392","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-07-09T16:38:04.649456Z","caller":"traceutil/trace.go:171","msg":"trace[306003817] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"304.761956ms","start":"2026-07-09T16:38:04.344663Z","end":"2026-07-09T16:38:04.649425Z","steps":["trace[306003817] 'process raft request' (duration: 233.269616ms)","trace[306003817] 'compare' (duration: 70.998265ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:04.650011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:04.344645Z","time spent":"305.333864ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:04.651469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:04.345512Z","time spent":"304.005263ms","remote":"127.0.0.1:34392","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-09T16:38:04.652348Z","caller":"traceutil/trace.go:171","msg":"trace[836202900] linearizableReadLoop","detail":"{readStateIndex:686; appliedIndex:680; }","duration":"160.979873ms","start":"2026-07-09T16:38:04.49134Z","end":"2026-07-09T16:38:04.652319Z","steps":["trace[836202900] 'read index received' (duration: 86.560553ms)","trace[836202900] 'applied index is now lower than readState.Index' (duration: 74.41745ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:04.652545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.18679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:38:04.652604Z","caller":"traceutil/trace.go:171","msg":"trace[1172579371] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:662; }","duration":"161.324273ms","start":"2026-07-09T16:38:04.491264Z","end":"2026-07-09T16:38:04.652588Z","steps":["trace[1172579371] 'agreement among raft nodes before linearized reading' (duration: 161.165398ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:04.652703Z","caller":"traceutil/trace.go:171","msg":"trace[884827644] transaction","detail":"{read_only:false; response_revision:662; number_of_response:1; }","duration":"255.852861ms","start":"2026-07-09T16:38:04.396579Z","end":"2026-07-09T16:38:04.652432Z","steps":["trace[884827644] 'process raft request' (duration: 255.560312ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:05.529458Z","caller":"traceutil/trace.go:171","msg":"trace[1921863956] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"129.085785ms","start":"2026-07-09T16:38:05.400341Z","end":"2026-07-09T16:38:05.529427Z","steps":["trace[1921863956] 'process raft request' (duration: 128.858268ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:05.608475Z","caller":"traceutil/trace.go:171","msg":"trace[123476265] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"117.253041ms","start":"2026-07-09T16:38:05.491091Z","end":"2026-07-09T16:38:05.608344Z","steps":["trace[123476265] 'read index received' (duration: 38.345261ms)","trace[123476265] 'applied index is now lower than readState.Index' (duration: 78.90662ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:05.608499Z","caller":"traceutil/trace.go:171","msg":"trace[620033282] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"200.076401ms","start":"2026-07-09T16:38:05.408366Z","end":"2026-07-09T16:38:05.608442Z","steps":["trace[620033282] 'process raft request' (duration: 174.256086ms)","trace[620033282] 'compare' (duration: 25.589208ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:05.608663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.558951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-07-09T16:38:05.608698Z","caller":"traceutil/trace.go:171","msg":"trace[483698490] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:670; }","duration":"117.653313ms","start":"2026-07-09T16:38:05.491032Z","end":"2026-07-09T16:38:05.608685Z","steps":["trace[483698490] 'agreement among raft nodes before linearized reading' (duration: 117.502699ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:06.477338Z","caller":"traceutil/trace.go:171","msg":"trace[369987848] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"156.40869ms","start":"2026-07-09T16:38:06.3209Z","end":"2026-07-09T16:38:06.477309Z","steps":["trace[369987848] 'process raft request' (duration: 153.977915ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.001088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.617797ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:38:07.001237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.731093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:38:07.001277Z","caller":"traceutil/trace.go:171","msg":"trace[1630035753] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:678; }","duration":"351.835563ms","start":"2026-07-09T16:38:06.649412Z","end":"2026-07-09T16:38:07.001248Z","steps":["trace[1630035753] 'range keys from in-memory index tree' (duration: 351.585406ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:07.001343Z","caller":"traceutil/trace.go:171","msg":"trace[1952843843] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"506.248243ms","start":"2026-07-09T16:38:06.495077Z","end":"2026-07-09T16:38:07.001325Z","steps":["trace[1952843843] 'process raft request' (duration: 310.326864ms)","trace[1952843843] 'compare' (duration: 195.587949ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:07.001424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.495057Z","time spent":"506.330606ms","remote":"127.0.0.1:34316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18322,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:07.203631Z","caller":"traceutil/trace.go:171","msg":"trace[1355646022] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"678.800739ms","start":"2026-07-09T16:38:06.524795Z","end":"2026-07-09T16:38:07.203595Z","steps":["trace[1355646022] 'process raft request' (duration: 581.058443ms)","trace[1355646022] 'compare' (duration: 97.26705ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:07.203766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.524755Z","time spent":"678.950363ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187246,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T16:38:07.203762Z","caller":"traceutil/trace.go:171","msg":"trace[270428491] linearizableReadLoop","detail":"{readStateIndex:706; appliedIndex:703; }","duration":"549.306716ms","start":"2026-07-09T16:38:06.654381Z","end":"2026-07-09T16:38:07.203688Z","steps":["trace[270428491] 'read index received' (duration: 151.132568ms)","trace[270428491] 'applied index is now lower than readState.Index' (duration: 398.172728ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:07.203963Z","caller":"traceutil/trace.go:171","msg":"trace[1078496601] transaction","detail":"{read_only:false; response_revision:681; number_of_response:1; }","duration":"626.843566ms","start":"2026-07-09T16:38:06.577104Z","end":"2026-07-09T16:38:07.203947Z","steps":["trace[1078496601] 'process raft request' (duration: 626.419133ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.577076Z","time spent":"626.935109ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:07.204221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.785702ms","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-07-09T16:38:07.204365Z","caller":"traceutil/trace.go:171","msg":"trace[1880305026] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:681; }","duration":"550.214745ms","start":"2026-07-09T16:38:06.654094Z","end":"2026-07-09T16:38:07.204309Z","steps":["trace[1880305026] 'agreement among raft nodes before linearized reading' (duration: 549.907896ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.912854ms","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-07-09T16:38:07.20445Z","caller":"traceutil/trace.go:171","msg":"trace[1071858019] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:681; }","duration":"550.021268ms","start":"2026-07-09T16:38:06.654415Z","end":"2026-07-09T16:38:07.204436Z","steps":["trace[1071858019] 'agreement among raft nodes before linearized reading' (duration: 549.756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.654073Z","time spent":"550.337259ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-09T16:38:07.204491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.654377Z","time spent":"550.106011ms","remote":"127.0.0.1:34392","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":"warn","ts":"2026-07-09T16:38:07.204574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"513.717859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-09T16:38:07.20463Z","caller":"traceutil/trace.go:171","msg":"trace[1309025496] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"513.815912ms","start":"2026-07-09T16:38:06.690801Z","end":"2026-07-09T16:38:07.204617Z","steps":["trace[1309025496] 'agreement among raft nodes before linearized reading' (duration: 513.679278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.690777Z","time spent":"513.881744ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":1,"response size":10400,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-09T16:38:07.204683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.4103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-07-09T16:38:07.204756Z","caller":"traceutil/trace.go:171","msg":"trace[1006160892] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"113.542883ms","start":"2026-07-09T16:38:07.091193Z","end":"2026-07-09T16:38:07.204736Z","steps":["trace[1006160892] 'agreement among raft nodes before linearized reading' (duration: 113.284016ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.806852ms","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-09T16:38:07.204711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.595905ms","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-09T16:38:07.204841Z","caller":"traceutil/trace.go:171","msg":"trace[995906965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:681; }","duration":"294.866324ms","start":"2026-07-09T16:38:06.909966Z","end":"2026-07-09T16:38:07.204832Z","steps":["trace[995906965] 'agreement among raft nodes before linearized reading' (duration: 294.776322ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:07.20489Z","caller":"traceutil/trace.go:171","msg":"trace[1205003515] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:681; }","duration":"549.76435ms","start":"2026-07-09T16:38:06.655088Z","end":"2026-07-09T16:38:07.204852Z","steps":["trace[1205003515] 'agreement among raft nodes before linearized reading' (duration: 549.554084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.204925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.655045Z","time spent":"549.871534ms","remote":"127.0.0.1:34392","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-09T16:38:07.204856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.707955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-07-09T16:38:07.205031Z","caller":"traceutil/trace.go:171","msg":"trace[1168407801] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:681; }","duration":"313.943132ms","start":"2026-07-09T16:38:06.891045Z","end":"2026-07-09T16:38:07.204988Z","steps":["trace[1168407801] 'agreement among raft nodes before linearized reading' (duration: 313.413936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.205119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.891028Z","time spent":"314.074796ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4203,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" "} {"level":"warn","ts":"2026-07-09T16:38:07.205395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.915462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:38:07.20549Z","caller":"traceutil/trace.go:171","msg":"trace[1764929723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:681; }","duration":"374.999485ms","start":"2026-07-09T16:38:06.830463Z","end":"2026-07-09T16:38:07.205462Z","steps":["trace[1764929723] 'agreement among raft nodes before linearized reading' (duration: 374.131978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.205676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:06.830409Z","time spent":"375.227141ms","remote":"127.0.0.1:34122","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-09T16:38:07.756027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.976222ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:38:07.756137Z","caller":"traceutil/trace.go:171","msg":"trace[68857142] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"542.555369ms","start":"2026-07-09T16:38:07.213562Z","end":"2026-07-09T16:38:07.756118Z","steps":["trace[68857142] 'process raft request' (duration: 257.409512ms)","trace[68857142] 'compare' (duration: 284.594721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:07.756202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.213548Z","time spent":"542.622601ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:07.75989Z","caller":"traceutil/trace.go:171","msg":"trace[910335151] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"544.787766ms","start":"2026-07-09T16:38:07.215084Z","end":"2026-07-09T16:38:07.759872Z","steps":["trace[910335151] 'process raft request' (duration: 544.678803ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:07.759881Z","caller":"traceutil/trace.go:171","msg":"trace[736375313] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"545.689445ms","start":"2026-07-09T16:38:07.214161Z","end":"2026-07-09T16:38:07.75985Z","steps":["trace[736375313] 'process raft request' (duration: 545.496089ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.759971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.215062Z","time spent":"544.868919ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:07.760008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.214121Z","time spent":"545.8193ms","remote":"127.0.0.1:34392","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-09T16:38:07.760151Z","caller":"traceutil/trace.go:171","msg":"trace[232355306] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"544.391135ms","start":"2026-07-09T16:38:07.215728Z","end":"2026-07-09T16:38:07.760119Z","steps":["trace[232355306] 'process raft request' (duration: 544.084906ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:07.760224Z","caller":"traceutil/trace.go:171","msg":"trace[2027410479] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"483.602739ms","start":"2026-07-09T16:38:07.276602Z","end":"2026-07-09T16:38:07.760205Z","steps":["trace[2027410479] 'process raft request' (duration: 483.297749ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:07.760224Z","caller":"traceutil/trace.go:171","msg":"trace[976285958] linearizableReadLoop","detail":"{readStateIndex:711; appliedIndex:706; }","duration":"469.675719ms","start":"2026-07-09T16:38:07.290521Z","end":"2026-07-09T16:38:07.760197Z","steps":["trace[976285958] 'read index received' (duration: 180.499178ms)","trace[976285958] 'applied index is now lower than readState.Index' (duration: 289.175731ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:07.760323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.276577Z","time spent":"483.704702ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:07.76033Z","caller":"traceutil/trace.go:171","msg":"trace[2002886400] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"451.324673ms","start":"2026-07-09T16:38:07.308995Z","end":"2026-07-09T16:38:07.76032Z","steps":["trace[2002886400] 'process raft request' (duration: 451.085186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.760417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.895866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-07-09T16:38:07.760429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.215719Z","time spent":"544.55335ms","remote":"127.0.0.1:34392","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:07.760462Z","caller":"traceutil/trace.go:171","msg":"trace[133935183] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:687; }","duration":"469.972218ms","start":"2026-07-09T16:38:07.290478Z","end":"2026-07-09T16:38:07.760451Z","steps":["trace[133935183] 'agreement among raft nodes before linearized reading' (duration: 469.868975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:07.761333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.290456Z","time spent":"470.855726ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":1,"response size":188518,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-09T16:38:07.760475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.308945Z","time spent":"451.484438ms","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:38:07.795478Z","caller":"traceutil/trace.go:171","msg":"trace[1391917737] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"169.033305ms","start":"2026-07-09T16:38:07.626415Z","end":"2026-07-09T16:38:07.795448Z","steps":["trace[1391917737] 'process raft request' (duration: 168.389115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:08.73076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952156358,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-09T16:38:09.142942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.011566069s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-09T16:38:09.14307Z","caller":"traceutil/trace.go:171","msg":"trace[1218812824] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.335143537s","start":"2026-07-09T16:38:07.807902Z","end":"2026-07-09T16:38:09.143045Z","steps":["trace[1218812824] 'process raft request' (duration: 323.360841ms)","trace[1218812824] 'compare' (duration: 1.011209648s)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:38:09.143142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.807882Z","time spent":"1.335228219s","remote":"127.0.0.1:34206","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-09T16:38:09.231248Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":8055707488952156358,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-09T16:38:09.236984Z","caller":"traceutil/trace.go:171","msg":"trace[664889369] linearizableReadLoop","detail":"{readStateIndex:715; appliedIndex:714; }","duration":"1.007184387s","start":"2026-07-09T16:38:08.22977Z","end":"2026-07-09T16:38:09.236955Z","steps":["trace[664889369] 'read index received' (duration: 1.006783005s)","trace[664889369] 'applied index is now lower than readState.Index' (duration: 400.002µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:09.237106Z","caller":"traceutil/trace.go:171","msg":"trace[492590368] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"1.389633453s","start":"2026-07-09T16:38:07.847455Z","end":"2026-07-09T16:38:09.237088Z","steps":["trace[492590368] 'process raft request' (duration: 1.389248032s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.237273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:07.847427Z","time spent":"1.389726627s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-09T16:38:09.237385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.51605ms","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-07-09T16:38:09.237486Z","caller":"traceutil/trace.go:171","msg":"trace[488621693] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:690; }","duration":"372.660454ms","start":"2026-07-09T16:38:08.864813Z","end":"2026-07-09T16:38:09.237473Z","steps":["trace[488621693] 'agreement among raft nodes before linearized reading' (duration: 372.52222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.237528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:08.864786Z","time spent":"372.731746ms","remote":"127.0.0.1:34218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-07-09T16:38:09.237524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.620144ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T16:38:09.237564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.007785356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-07-09T16:38:09.237595Z","caller":"traceutil/trace.go:171","msg":"trace[1678331516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:690; }","duration":"407.751438ms","start":"2026-07-09T16:38:08.829825Z","end":"2026-07-09T16:38:09.237576Z","steps":["trace[1678331516] 'agreement among raft nodes before linearized reading' (duration: 407.558242ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:09.237616Z","caller":"traceutil/trace.go:171","msg":"trace[824770523] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:690; }","duration":"1.007854619s","start":"2026-07-09T16:38:08.229743Z","end":"2026-07-09T16:38:09.237598Z","steps":["trace[824770523] 'agreement among raft nodes before linearized reading' (duration: 1.007738735s)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.237649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:08.829803Z","time spent":"407.8384ms","remote":"127.0.0.1:34122","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-09T16:38:09.237733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:08.229727Z","time spent":"1.00792116s","remote":"127.0.0.1:34186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":1,"response size":188275,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-07-09T16:38:09.237753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.965516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T16:38:09.237788Z","caller":"traceutil/trace.go:171","msg":"trace[640412500] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:690; }","duration":"297.040059ms","start":"2026-07-09T16:38:08.940735Z","end":"2026-07-09T16:38:09.237775Z","steps":["trace[640412500] 'agreement among raft nodes before linearized reading' (duration: 296.987798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.237994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"748.910523ms","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-09T16:38:09.238047Z","caller":"traceutil/trace.go:171","msg":"trace[1110297957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:690; }","duration":"749.046877ms","start":"2026-07-09T16:38:08.488987Z","end":"2026-07-09T16:38:09.238034Z","steps":["trace[1110297957] 'agreement among raft nodes before linearized reading' (duration: 748.901593ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.238094Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:08.48896Z","time spent":"749.12255ms","remote":"127.0.0.1:34392","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-07-09T16:38:09.238098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.602044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bfzf8\" ","response":"range_response_count:1 size:18337"} {"level":"info","ts":"2026-07-09T16:38:09.238147Z","caller":"traceutil/trace.go:171","msg":"trace[756263313] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bfzf8; range_end:; response_count:1; response_revision:690; }","duration":"818.662326ms","start":"2026-07-09T16:38:08.419459Z","end":"2026-07-09T16:38:09.238122Z","steps":["trace[756263313] 'agreement among raft nodes before linearized reading' (duration: 818.558803ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:09.23818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:08.419441Z","time spent":"818.732608ms","remote":"127.0.0.1:34316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18360,"request content":"key:\"/registry/pods/kube-system/cilium-bfzf8\" "} {"level":"info","ts":"2026-07-09T16:38:09.37463Z","caller":"traceutil/trace.go:171","msg":"trace[440391613] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"132.29458ms","start":"2026-07-09T16:38:09.242295Z","end":"2026-07-09T16:38:09.374589Z","steps":["trace[440391613] 'process raft request' (duration: 100.471486ms)","trace[440391613] 'compare' (duration: 31.648578ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:38:09.387029Z","caller":"traceutil/trace.go:171","msg":"trace[1692725597] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"136.502709ms","start":"2026-07-09T16:38:09.250504Z","end":"2026-07-09T16:38:09.387007Z","steps":["trace[1692725597] 'process raft request' (duration: 136.362355ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:19.640388Z","caller":"traceutil/trace.go:171","msg":"trace[710890202] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"156.000631ms","start":"2026-07-09T16:38:19.484349Z","end":"2026-07-09T16:38:19.640349Z","steps":["trace[710890202] 'process raft request' (duration: 154.326509ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:38:21.530245Z","caller":"traceutil/trace.go:171","msg":"trace[1456241742] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"879.474457ms","start":"2026-07-09T16:38:20.650723Z","end":"2026-07-09T16:38:21.530198Z","steps":["trace[1456241742] 'process raft request' (duration: 879.186988ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:38:21.530485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T16:38:20.650692Z","time spent":"879.659113ms","remote":"127.0.0.1:34362","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T16:39:28.454763Z","caller":"traceutil/trace.go:171","msg":"trace[1053445744] linearizableReadLoop","detail":"{readStateIndex:1173; appliedIndex:1172; }","duration":"103.023732ms","start":"2026-07-09T16:39:28.351718Z","end":"2026-07-09T16:39:28.454742Z","steps":["trace[1053445744] 'read index received' (duration: 102.771303ms)","trace[1053445744] 'applied index is now lower than readState.Index' (duration: 251.608µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:28.454871Z","caller":"traceutil/trace.go:171","msg":"trace[391448907] transaction","detail":"{read_only:false; response_revision:1129; number_of_response:1; }","duration":"115.960107ms","start":"2026-07-09T16:39:28.338862Z","end":"2026-07-09T16:39:28.454822Z","steps":["trace[391448907] 'process raft request' (duration: 115.75157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:39:28.454964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.234008ms","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-09T16:39:28.455115Z","caller":"traceutil/trace.go:171","msg":"trace[1412272680] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1129; }","duration":"103.414505ms","start":"2026-07-09T16:39:28.351658Z","end":"2026-07-09T16:39:28.455073Z","steps":["trace[1412272680] 'agreement among raft nodes before linearized reading' (duration: 103.197337ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:28.670865Z","caller":"traceutil/trace.go:171","msg":"trace[1560509484] transaction","detail":"{read_only:false; response_revision:1132; number_of_response:1; }","duration":"210.466017ms","start":"2026-07-09T16:39:28.460375Z","end":"2026-07-09T16:39:28.670841Z","steps":["trace[1560509484] 'process raft request' (duration: 210.363443ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:28.670883Z","caller":"traceutil/trace.go:171","msg":"trace[905705858] transaction","detail":"{read_only:false; response_revision:1131; number_of_response:1; }","duration":"210.798567ms","start":"2026-07-09T16:39:28.460058Z","end":"2026-07-09T16:39:28.670857Z","steps":["trace[905705858] 'process raft request' (duration: 210.437235ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:29.828209Z","caller":"traceutil/trace.go:171","msg":"trace[1147928690] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"109.239742ms","start":"2026-07-09T16:39:29.718949Z","end":"2026-07-09T16:39:29.828188Z","steps":["trace[1147928690] 'process raft request' (duration: 21.546942ms)","trace[1147928690] 'compare' (duration: 87.458733ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:32.63833Z","caller":"traceutil/trace.go:171","msg":"trace[135030159] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"120.235568ms","start":"2026-07-09T16:39:32.518063Z","end":"2026-07-09T16:39:32.638299Z","steps":["trace[135030159] 'process raft request' (duration: 113.707568ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:32.8334Z","caller":"traceutil/trace.go:171","msg":"trace[1889392900] transaction","detail":"{read_only:false; response_revision:1146; number_of_response:1; }","duration":"141.707809ms","start":"2026-07-09T16:39:32.691648Z","end":"2026-07-09T16:39:32.833356Z","steps":["trace[1889392900] 'process raft request' (duration: 67.750759ms)","trace[1889392900] 'compare' (duration: 73.817525ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:33.047347Z","caller":"traceutil/trace.go:171","msg":"trace[479507630] transaction","detail":"{read_only:false; response_revision:1147; number_of_response:1; }","duration":"117.003695ms","start":"2026-07-09T16:39:32.930309Z","end":"2026-07-09T16:39:33.047313Z","steps":["trace[479507630] 'process raft request' (duration: 59.563746ms)","trace[479507630] 'compare' (duration: 57.305455ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:39:46.810488Z","caller":"traceutil/trace.go:171","msg":"trace[319511193] transaction","detail":"{read_only:false; response_revision:1190; number_of_response:1; }","duration":"105.685016ms","start":"2026-07-09T16:39:46.704774Z","end":"2026-07-09T16:39:46.810459Z","steps":["trace[319511193] 'process raft request' (duration: 105.539544ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:39:55.245035Z","caller":"traceutil/trace.go:171","msg":"trace[765865961] transaction","detail":"{read_only:false; response_revision:1220; number_of_response:1; }","duration":"108.578235ms","start":"2026-07-09T16:39:55.136426Z","end":"2026-07-09T16:39:55.245004Z","steps":["trace[765865961] 'process raft request' (duration: 60.659107ms)","trace[765865961] 'compare' (duration: 47.847607ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:11.366988Z","caller":"traceutil/trace.go:171","msg":"trace[1161627128] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"132.834127ms","start":"2026-07-09T16:40:11.234127Z","end":"2026-07-09T16:40:11.366961Z","steps":["trace[1161627128] 'process raft request' (duration: 115.423465ms)","trace[1161627128] 'compare' (duration: 17.294831ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:11.514294Z","caller":"traceutil/trace.go:171","msg":"trace[1236769101] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"179.779882ms","start":"2026-07-09T16:40:11.334475Z","end":"2026-07-09T16:40:11.514255Z","steps":["trace[1236769101] 'process raft request' (duration: 165.506864ms)","trace[1236769101] 'compare' (duration: 14.037664ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:12.394372Z","caller":"traceutil/trace.go:171","msg":"trace[1743593758] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1358; }","duration":"196.446021ms","start":"2026-07-09T16:40:12.197896Z","end":"2026-07-09T16:40:12.394342Z","steps":["trace[1743593758] 'read index received' (duration: 196.215077ms)","trace[1743593758] 'applied index is now lower than readState.Index' (duration: 229.894µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T16:40:12.39443Z","caller":"traceutil/trace.go:171","msg":"trace[523181318] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"290.047577ms","start":"2026-07-09T16:40:12.104348Z","end":"2026-07-09T16:40:12.394395Z","steps":["trace[523181318] 'process raft request' (duration: 289.821272ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T16:40:12.394577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.666416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-09T16:40:12.394616Z","caller":"traceutil/trace.go:171","msg":"trace[2082225385] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1304; }","duration":"196.772288ms","start":"2026-07-09T16:40:12.197831Z","end":"2026-07-09T16:40:12.394603Z","steps":["trace[2082225385] 'agreement among raft nodes before linearized reading' (duration: 196.613965ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:13.499318Z","caller":"traceutil/trace.go:171","msg":"trace[1458534202] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"232.587446ms","start":"2026-07-09T16:40:13.266714Z","end":"2026-07-09T16:40:13.499301Z","steps":["trace[1458534202] 'process raft request' (duration: 232.394633ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T16:40:13.499303Z","caller":"traceutil/trace.go:171","msg":"trace[965330619] linearizableReadLoop","detail":"{readStateIndex:1363; appliedIndex:1362; }","duration":"127.045402ms","start":"2026-07-09T16:40:13.372228Z","end":"2026-07-09T16:40:13.499273Z","steps":["trace[965330619] 'read index received' (duration: 126.805278ms)","trace[965330619] 'applied index is now lower than readState.Index' (duration: 238.654µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T16:40:13.499539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.294526ms","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-07-09T16:40:13.499596Z","caller":"traceutil/trace.go:171","msg":"trace[551529792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1308; }","duration":"127.429358ms","start":"2026-07-09T16:40:13.37215Z","end":"2026-07-09T16:40:13.499579Z","steps":["trace[551529792] 'agreement among raft nodes before linearized reading' (duration: 127.213574ms)"],"step_count":1}