{"level":"warn","ts":"2026-08-13T01:04:59.38902Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T01:04:59.389095Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.38:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.38:2380","--initial-cluster=instance=https://199.204.45.38:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.38:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.38: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-08-13T01:04:59.389151Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-13T01:04:59.389162Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-13T01:04:59.389179Z","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-08-13T01:04:59.389783Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"]} {"level":"info","ts":"2026-08-13T01:04:59.389855Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.38: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-08-13T01:04:59.391962Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.901909ms"} {"level":"info","ts":"2026-08-13T01:04:59.39758Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"331455d6226d3423","cluster-id":"25c7803fb83a1455"} {"level":"info","ts":"2026-08-13T01:04:59.397646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T01:04:59.397668Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 0"} {"level":"info","ts":"2026-08-13T01:04:59.39768Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 331455d6226d3423 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T01:04:59.397696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became follower at term 1"} {"level":"info","ts":"2026-08-13T01:04:59.397735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"warn","ts":"2026-08-13T01:04:59.406442Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T01:04:59.40833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T01:04:59.409722Z","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-08-13T01:04:59.412607Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"331455d6226d3423","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T01:04:59.413125Z","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-08-13T01:04:59.413193Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"331455d6226d3423","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T01:04:59.413269Z","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-08-13T01:04:59.41333Z","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-08-13T01:04:59.415532Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 switched to configuration voters=(3680661173657482275)"} {"level":"info","ts":"2026-08-13T01:04:59.416261Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","added-peer-id":"331455d6226d3423","added-peer-peer-urls":["https://199.204.45.38:2380"]} {"level":"info","ts":"2026-08-13T01:04:59.418694Z","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-08-13T01:04:59.418923Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-13T01:04:59.418972Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.38:2380"} {"level":"info","ts":"2026-08-13T01:04:59.419207Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"331455d6226d3423","initial-advertise-peer-urls":["https://199.204.45.38:2380"],"listen-peer-urls":["https://199.204.45.38:2380"],"advertise-client-urls":["https://199.204.45.38:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.38:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T01:04:59.419256Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T01:04:59.998067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T01:04:59.998139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T01:04:59.998176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgPreVoteResp from 331455d6226d3423 at term 1"} {"level":"info","ts":"2026-08-13T01:04:59.998195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became candidate at term 2"} {"level":"info","ts":"2026-08-13T01:04:59.998206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 received MsgVoteResp from 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-13T01:04:59.998222Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"331455d6226d3423 became leader at term 2"} {"level":"info","ts":"2026-08-13T01:04:59.998237Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 331455d6226d3423 elected leader 331455d6226d3423 at term 2"} {"level":"info","ts":"2026-08-13T01:04:59.999204Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"331455d6226d3423","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.38:2379]}","request-path":"/0/members/331455d6226d3423/attributes","cluster-id":"25c7803fb83a1455","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T01:04:59.999229Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:04:59.999319Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T01:04:59.999485Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T01:04:59.999987Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T01:05:00.000041Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T01:05:00.000195Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"25c7803fb83a1455","local-member-id":"331455d6226d3423","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:05:00.000311Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:05:00.000341Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T01:05:00.003061Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T01:05:00.003086Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.38:2379"} {"level":"info","ts":"2026-08-13T01:05:15.57958Z","caller":"traceutil/trace.go:171","msg":"trace[1485415303] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"121.742564ms","start":"2026-08-13T01:05:15.457801Z","end":"2026-08-13T01:05:15.579543Z","steps":["trace[1485415303] 'process raft request' (duration: 121.596851ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:15.784508Z","caller":"traceutil/trace.go:171","msg":"trace[946740496] linearizableReadLoop","detail":"{readStateIndex:427; appliedIndex:425; }","duration":"188.401431ms","start":"2026-08-13T01:05:15.596087Z","end":"2026-08-13T01:05:15.784489Z","steps":["trace[946740496] 'read index received' (duration: 106.351878ms)","trace[946740496] 'applied index is now lower than readState.Index' (duration: 82.048653ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:05:15.790722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.621215ms","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-08-13T01:05:15.790844Z","caller":"traceutil/trace.go:171","msg":"trace[1296815849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"194.762248ms","start":"2026-08-13T01:05:15.596049Z","end":"2026-08-13T01:05:15.790812Z","steps":["trace[1296815849] 'agreement among raft nodes before linearized reading' (duration: 188.539433ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:15.790757Z","caller":"traceutil/trace.go:171","msg":"trace[601883721] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"207.556742ms","start":"2026-08-13T01:05:15.583149Z","end":"2026-08-13T01:05:15.790705Z","steps":["trace[601883721] 'process raft request' (duration: 119.272314ms)","trace[601883721] 'compare' (duration: 81.745127ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:05:15.790875Z","caller":"traceutil/trace.go:171","msg":"trace[899560973] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"207.44892ms","start":"2026-08-13T01:05:15.583263Z","end":"2026-08-13T01:05:15.790712Z","steps":["trace[899560973] 'process raft request' (duration: 201.186525ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:16.572941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.745242ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:05:16.573044Z","caller":"traceutil/trace.go:171","msg":"trace[1935230668] linearizableReadLoop","detail":"{readStateIndex:428; appliedIndex:427; }","duration":"741.544784ms","start":"2026-08-13T01:05:15.831484Z","end":"2026-08-13T01:05:16.573029Z","steps":["trace[1935230668] 'read index received' (duration: 278.187239ms)","trace[1935230668] 'applied index is now lower than readState.Index' (duration: 463.356105ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:05:16.573103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"741.616436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:05:16.573128Z","caller":"traceutil/trace.go:171","msg":"trace[841286206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:413; }","duration":"741.670387ms","start":"2026-08-13T01:05:15.831449Z","end":"2026-08-13T01:05:16.573119Z","steps":["trace[841286206] 'agreement among raft nodes before linearized reading' (duration: 741.622896ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:16.573097Z","caller":"traceutil/trace.go:171","msg":"trace[1613567063] transaction","detail":"{read_only:false; response_revision:413; number_of_response:1; }","duration":"779.451009ms","start":"2026-08-13T01:05:15.793584Z","end":"2026-08-13T01:05:16.573035Z","steps":["trace[1613567063] 'process raft request' (duration: 316.028223ms)","trace[1613567063] 'compare' (duration: 462.434146ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:05:16.573161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:15.831432Z","time spent":"741.722548ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:05:16.573261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:15.793563Z","time spent":"779.605742ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:05:36.953377Z","caller":"traceutil/trace.go:171","msg":"trace[725715403] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"294.069385ms","start":"2026-08-13T01:05:36.659281Z","end":"2026-08-13T01:05:36.95335Z","steps":["trace[725715403] 'process raft request' (duration: 293.907791ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.221302Z","caller":"traceutil/trace.go:171","msg":"trace[1264345682] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"559.019959ms","start":"2026-08-13T01:05:36.662234Z","end":"2026-08-13T01:05:37.221254Z","steps":["trace[1264345682] 'process raft request' (duration: 558.720483ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.221468Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.662205Z","time spent":"559.183363ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.221447Z","caller":"traceutil/trace.go:171","msg":"trace[678965964] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"555.298206ms","start":"2026-08-13T01:05:36.666122Z","end":"2026-08-13T01:05:37.221421Z","steps":["trace[678965964] 'process raft request' (duration: 554.997731ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.221694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.666102Z","time spent":"555.50444ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.222378Z","caller":"traceutil/trace.go:171","msg":"trace[635552304] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"546.778446ms","start":"2026-08-13T01:05:36.675581Z","end":"2026-08-13T01:05:37.22236Z","steps":["trace[635552304] 'process raft request' (duration: 546.563092ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.222455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.675565Z","time spent":"546.858318ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.22256Z","caller":"traceutil/trace.go:171","msg":"trace[745731210] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"535.94386ms","start":"2026-08-13T01:05:36.686579Z","end":"2026-08-13T01:05:37.222523Z","steps":["trace[745731210] 'process raft request' (duration: 535.702166ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.222628Z","caller":"traceutil/trace.go:171","msg":"trace[1548155510] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"511.255818ms","start":"2026-08-13T01:05:36.71136Z","end":"2026-08-13T01:05:37.222615Z","steps":["trace[1548155510] 'process raft request' (duration: 510.991303ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.222633Z","caller":"traceutil/trace.go:171","msg":"trace[659569547] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"548.358258ms","start":"2026-08-13T01:05:36.674247Z","end":"2026-08-13T01:05:37.222606Z","steps":["trace[659569547] 'process raft request' (duration: 547.763976ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.222727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.711341Z","time spent":"511.360331ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.222691Z","caller":"traceutil/trace.go:171","msg":"trace[1296979551] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"535.965551ms","start":"2026-08-13T01:05:36.686634Z","end":"2026-08-13T01:05:37.2226Z","steps":["trace[1296979551] 'process raft request' (duration: 535.680745ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.222756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.674221Z","time spent":"548.46605ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:37.222859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.686576Z","time spent":"536.127574ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:37.223064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.686631Z","time spent":"536.320468ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.223538Z","caller":"traceutil/trace.go:171","msg":"trace[2022994577] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"536.98741ms","start":"2026-08-13T01:05:36.686524Z","end":"2026-08-13T01:05:37.223511Z","steps":["trace[2022994577] 'process raft request' (duration: 535.715706ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.223548Z","caller":"traceutil/trace.go:171","msg":"trace[243209540] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"500.565847ms","start":"2026-08-13T01:05:36.722964Z","end":"2026-08-13T01:05:37.22353Z","steps":["trace[243209540] 'process raft request' (duration: 499.436634ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.223534Z","caller":"traceutil/trace.go:171","msg":"trace[1748185510] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"547.644883ms","start":"2026-08-13T01:05:36.675788Z","end":"2026-08-13T01:05:37.223433Z","steps":["trace[1748185510] 'process raft request' (duration: 546.404779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.223634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.686505Z","time spent":"537.089123ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:37.223623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.722938Z","time spent":"500.662008ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:37.223804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.675781Z","time spent":"547.923809ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:05:37.225206Z","caller":"traceutil/trace.go:171","msg":"trace[1810892584] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"263.448825ms","start":"2026-08-13T01:05:36.961747Z","end":"2026-08-13T01:05:37.225195Z","steps":["trace[1810892584] 'process raft request' (duration: 263.408194ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:37.225288Z","caller":"traceutil/trace.go:171","msg":"trace[38174995] linearizableReadLoop","detail":"{readStateIndex:547; appliedIndex:544; }","duration":"551.137533ms","start":"2026-08-13T01:05:36.674138Z","end":"2026-08-13T01:05:37.225276Z","steps":["trace[38174995] 'read index received' (duration: 279.110027ms)","trace[38174995] 'applied index is now lower than readState.Index' (duration: 272.026106ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:05:37.225344Z","caller":"traceutil/trace.go:171","msg":"trace[343880940] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"400.976073ms","start":"2026-08-13T01:05:36.824354Z","end":"2026-08-13T01:05:37.22533Z","steps":["trace[343880940] 'process raft request' (duration: 400.598416ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.225955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.824331Z","time spent":"401.595666ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:37.225956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.105325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:05:37.226002Z","caller":"traceutil/trace.go:171","msg":"trace[674645859] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"401.151676ms","start":"2026-08-13T01:05:36.824837Z","end":"2026-08-13T01:05:37.225988Z","steps":["trace[674645859] 'agreement among raft nodes before linearized reading' (duration: 400.799549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.225402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"551.219725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-13T01:05:37.226042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.824801Z","time spent":"401.233178ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:05:37.226065Z","caller":"traceutil/trace.go:171","msg":"trace[1058508244] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:538; }","duration":"551.936418ms","start":"2026-08-13T01:05:36.674108Z","end":"2026-08-13T01:05:37.226044Z","steps":["trace[1058508244] 'agreement among raft nodes before linearized reading' (duration: 551.199934ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:37.226142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:36.674095Z","time spent":"552.025891ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T01:05:37.225452Z","caller":"traceutil/trace.go:171","msg":"trace[482567020] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"266.976615ms","start":"2026-08-13T01:05:36.958459Z","end":"2026-08-13T01:05:37.225436Z","steps":["trace[482567020] 'process raft request' (duration: 266.659219ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:05:43.73612Z","caller":"traceutil/trace.go:171","msg":"trace[264667325] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"334.152198ms","start":"2026-08-13T01:05:43.401941Z","end":"2026-08-13T01:05:43.736093Z","steps":["trace[264667325] 'process raft request' (duration: 307.749385ms)","trace[264667325] 'compare' (duration: 25.559266ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:05:43.736242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:43.401921Z","time spent":"334.274611ms","remote":"127.0.0.1:50814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:44.322905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022404453862021,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:05:45.356561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.322953ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:05:45.356692Z","caller":"traceutil/trace.go:171","msg":"trace[1413348811] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:662; }","duration":"1.534073993s","start":"2026-08-13T01:05:43.822592Z","end":"2026-08-13T01:05:45.356666Z","steps":["trace[1413348811] 'read index received' (duration: 743.555467ms)","trace[1413348811] 'applied index is now lower than readState.Index' (duration: 790.517026ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:05:45.356784Z","caller":"traceutil/trace.go:171","msg":"trace[256035470] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"1.5425248s","start":"2026-08-13T01:05:43.814244Z","end":"2026-08-13T01:05:45.356769Z","steps":["trace[256035470] 'process raft request' (duration: 751.891991ms)","trace[256035470] 'compare' (duration: 790.19276ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:05:45.356862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:43.814227Z","time spent":"1.542600971s","remote":"127.0.0.1:50774","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:05:45.357703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.535055582s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:05:45.357858Z","caller":"traceutil/trace.go:171","msg":"trace[696518686] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"1.535254525s","start":"2026-08-13T01:05:43.822558Z","end":"2026-08-13T01:05:45.357812Z","steps":["trace[696518686] 'agreement among raft nodes before linearized reading' (duration: 1.534397719s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:45.35796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:43.822546Z","time spent":"1.535399289s","remote":"127.0.0.1:50544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:05:45.366298Z","caller":"traceutil/trace.go:171","msg":"trace[1875107490] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"312.101802ms","start":"2026-08-13T01:05:45.054176Z","end":"2026-08-13T01:05:45.366278Z","steps":["trace[1875107490] 'process raft request' (duration: 311.98927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:45.366385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.435762436s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-13T01:05:45.366424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:45.054145Z","time spent":"312.213143ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:05:45.366434Z","caller":"traceutil/trace.go:171","msg":"trace[662109762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:639; }","duration":"1.435845447s","start":"2026-08-13T01:05:43.930578Z","end":"2026-08-13T01:05:45.366424Z","steps":["trace[662109762] 'agreement among raft nodes before linearized reading' (duration: 1.435695594s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:45.366463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:43.930564Z","time spent":"1.435893907s","remote":"127.0.0.1:50824","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-08-13T01:05:45.366444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439879857s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-13T01:05:45.366538Z","caller":"traceutil/trace.go:171","msg":"trace[528404988] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:639; }","duration":"1.44001584s","start":"2026-08-13T01:05:43.926502Z","end":"2026-08-13T01:05:45.366518Z","steps":["trace[528404988] 'agreement among raft nodes before linearized reading' (duration: 1.439813686s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:05:45.366582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:05:43.926482Z","time spent":"1.440092611s","remote":"127.0.0.1:50824","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-08-13T01:05:47.195818Z","caller":"traceutil/trace.go:171","msg":"trace[1457290852] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"160.919027ms","start":"2026-08-13T01:05:47.034873Z","end":"2026-08-13T01:05:47.195792Z","steps":["trace[1457290852] 'process raft request' (duration: 160.772675ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:06:25.746619Z","caller":"traceutil/trace.go:171","msg":"trace[1273725543] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"117.565439ms","start":"2026-08-13T01:06:25.629033Z","end":"2026-08-13T01:06:25.746598Z","steps":["trace[1273725543] 'process raft request' (duration: 87.265768ms)","trace[1273725543] 'compare' (duration: 30.016034ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:06:26.907235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.746198ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:06:26.90736Z","caller":"traceutil/trace.go:171","msg":"trace[1760612815] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"589.063286ms","start":"2026-08-13T01:06:26.318282Z","end":"2026-08-13T01:06:26.907345Z","steps":["trace[1760612815] 'process raft request' (duration: 455.145434ms)","trace[1760612815] 'compare' (duration: 132.666838ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:06:26.907403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:06:26.318258Z","time spent":"589.125218ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:06:27.430951Z","caller":"traceutil/trace.go:171","msg":"trace[117450434] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"519.58831ms","start":"2026-08-13T01:06:26.911343Z","end":"2026-08-13T01:06:27.430932Z","steps":["trace[117450434] 'process raft request' (duration: 519.483848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:06:27.431062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:06:26.911322Z","time spent":"519.683184ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:06:27.585771Z","caller":"traceutil/trace.go:171","msg":"trace[1344903680] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"610.042562ms","start":"2026-08-13T01:06:26.975698Z","end":"2026-08-13T01:06:27.58574Z","steps":["trace[1344903680] 'process raft request' (duration: 517.598641ms)","trace[1344903680] 'compare' (duration: 92.332899ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:06:27.585942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:06:26.975676Z","time spent":"610.163115ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:06:46.133065Z","caller":"traceutil/trace.go:171","msg":"trace[1336383462] transaction","detail":"{read_only:false; response_revision:1051; number_of_response:1; }","duration":"215.823364ms","start":"2026-08-13T01:06:45.917217Z","end":"2026-08-13T01:06:46.133041Z","steps":["trace[1336383462] 'process raft request' (duration: 215.655191ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:03.307447Z","caller":"traceutil/trace.go:171","msg":"trace[745687340] linearizableReadLoop","detail":"{readStateIndex:1201; appliedIndex:1200; }","duration":"465.011844ms","start":"2026-08-13T01:07:02.842416Z","end":"2026-08-13T01:07:03.307428Z","steps":["trace[745687340] 'read index received' (duration: 464.922292ms)","trace[745687340] 'applied index is now lower than readState.Index' (duration: 88.602µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:03.307474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:02.790844Z","time spent":"516.620318ms","remote":"127.0.0.1:50610","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-08-13T01:07:03.307627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.570012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:07:03.307667Z","caller":"traceutil/trace.go:171","msg":"trace[1563305942] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:1155; }","duration":"296.656353ms","start":"2026-08-13T01:07:03.010996Z","end":"2026-08-13T01:07:03.307652Z","steps":["trace[1563305942] 'agreement among raft nodes before linearized reading' (duration: 296.566362ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:03.307917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.497523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-13T01:07:03.307959Z","caller":"traceutil/trace.go:171","msg":"trace[2003964598] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1155; }","duration":"465.559315ms","start":"2026-08-13T01:07:02.842389Z","end":"2026-08-13T01:07:03.307948Z","steps":["trace[2003964598] 'agreement among raft nodes before linearized reading' (duration: 465.126877ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:03.307979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:02.842375Z","time spent":"465.598066ms","remote":"127.0.0.1:50928","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":55,"response size":29,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"info","ts":"2026-08-13T01:07:03.985865Z","caller":"traceutil/trace.go:171","msg":"trace[169249560] linearizableReadLoop","detail":"{readStateIndex:1202; appliedIndex:1201; }","duration":"346.327339ms","start":"2026-08-13T01:07:03.63952Z","end":"2026-08-13T01:07:03.985847Z","steps":["trace[169249560] 'read index received' (duration: 346.193827ms)","trace[169249560] 'applied index is now lower than readState.Index' (duration: 132.812µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:03.985937Z","caller":"traceutil/trace.go:171","msg":"trace[511995056] transaction","detail":"{read_only:false; response_revision:1156; number_of_response:1; }","duration":"674.910903ms","start":"2026-08-13T01:07:03.310972Z","end":"2026-08-13T01:07:03.985883Z","steps":["trace[511995056] 'process raft request' (duration: 674.76112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:03.986055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:03.310956Z","time spent":"675.036855ms","remote":"127.0.0.1:50610","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:03.986084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.546483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T01:07:03.986128Z","caller":"traceutil/trace.go:171","msg":"trace[1340094010] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1156; }","duration":"346.637555ms","start":"2026-08-13T01:07:03.63948Z","end":"2026-08-13T01:07:03.986117Z","steps":["trace[1340094010] 'agreement among raft nodes before linearized reading' (duration: 346.474012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:03.986161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:03.63946Z","time spent":"346.693766ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-13T01:07:03.987312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.603338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:03.987355Z","caller":"traceutil/trace.go:171","msg":"trace[1352186171] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1158; }","duration":"162.66507ms","start":"2026-08-13T01:07:03.82467Z","end":"2026-08-13T01:07:03.987335Z","steps":["trace[1352186171] 'agreement among raft nodes before linearized reading' (duration: 162.580268ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:03.987528Z","caller":"traceutil/trace.go:171","msg":"trace[1390414284] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"281.921737ms","start":"2026-08-13T01:07:03.7056Z","end":"2026-08-13T01:07:03.987522Z","steps":["trace[1390414284] 'process raft request' (duration: 281.551161ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:03.987628Z","caller":"traceutil/trace.go:171","msg":"trace[766794184] transaction","detail":"{read_only:false; response_revision:1158; number_of_response:1; }","duration":"161.931766ms","start":"2026-08-13T01:07:03.82569Z","end":"2026-08-13T01:07:03.987622Z","steps":["trace[766794184] 'process raft request' (duration: 161.538649ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:03.987806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.019483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-13T01:07:03.987822Z","caller":"traceutil/trace.go:171","msg":"trace[1284634316] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1158; }","duration":"100.063434ms","start":"2026-08-13T01:07:03.887755Z","end":"2026-08-13T01:07:03.987818Z","steps":["trace[1284634316] 'agreement among raft nodes before linearized reading' (duration: 100.020433ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:10.131732Z","caller":"traceutil/trace.go:171","msg":"trace[603623352] transaction","detail":"{read_only:false; response_revision:1179; number_of_response:1; }","duration":"103.210526ms","start":"2026-08-13T01:07:10.028484Z","end":"2026-08-13T01:07:10.131695Z","steps":["trace[603623352] 'process raft request' (duration: 101.025504ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:10.136406Z","caller":"traceutil/trace.go:171","msg":"trace[2122483733] transaction","detail":"{read_only:false; response_revision:1180; number_of_response:1; }","duration":"107.897676ms","start":"2026-08-13T01:07:10.028487Z","end":"2026-08-13T01:07:10.136385Z","steps":["trace[2122483733] 'process raft request' (duration: 107.779734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:18.924208Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022404453863292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:07:19.424598Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3757022404453863292,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-13T01:07:19.746756Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.361055902s","expected-duration":"1s"} {"level":"info","ts":"2026-08-13T01:07:19.747012Z","caller":"traceutil/trace.go:171","msg":"trace[1411976661] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"1.377301039s","start":"2026-08-13T01:07:18.369695Z","end":"2026-08-13T01:07:19.746996Z","steps":["trace[1411976661] 'process raft request' (duration: 1.377202886s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:19.747128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:18.369673Z","time spent":"1.37740007s","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:19.750906Z","caller":"traceutil/trace.go:171","msg":"trace[878398448] linearizableReadLoop","detail":"{readStateIndex:1278; appliedIndex:1277; }","duration":"1.327005631s","start":"2026-08-13T01:07:18.423878Z","end":"2026-08-13T01:07:19.750884Z","steps":["trace[878398448] 'read index received' (duration: 1.323088596s)","trace[878398448] 'applied index is now lower than readState.Index' (duration: 3.915735ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:19.751051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.48601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:07:19.751064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.327189204s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T01:07:19.751057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.143373494s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-13T01:07:19.751072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"934.616677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:19.751099Z","caller":"traceutil/trace.go:171","msg":"trace[557112959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1228; }","duration":"1.327274316s","start":"2026-08-13T01:07:18.423816Z","end":"2026-08-13T01:07:19.751091Z","steps":["trace[557112959] 'agreement among raft nodes before linearized reading' (duration: 1.327143863s)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:19.7511Z","caller":"traceutil/trace.go:171","msg":"trace[338502689] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1228; }","duration":"926.53466ms","start":"2026-08-13T01:07:18.824542Z","end":"2026-08-13T01:07:19.751077Z","steps":["trace[338502689] 'agreement among raft nodes before linearized reading' (duration: 926.464609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:19.751122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:18.423784Z","time spent":"1.327334108s","remote":"127.0.0.1:50824","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-08-13T01:07:19.751115Z","caller":"traceutil/trace.go:171","msg":"trace[931060952] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:1228; }","duration":"1.143495806s","start":"2026-08-13T01:07:18.607605Z","end":"2026-08-13T01:07:19.7511Z","steps":["trace[931060952] 'agreement among raft nodes before linearized reading' (duration: 1.143400144s)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:19.751129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:18.824505Z","time spent":"926.618571ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:07:19.751128Z","caller":"traceutil/trace.go:171","msg":"trace[72175863] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1228; }","duration":"934.719329ms","start":"2026-08-13T01:07:18.816394Z","end":"2026-08-13T01:07:19.751113Z","steps":["trace[72175863] 'agreement among raft nodes before linearized reading' (duration: 934.639218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:19.751157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:18.60758Z","time spent":"1.143567948s","remote":"127.0.0.1:36516","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-08-13T01:07:19.751162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:18.816371Z","time spent":"934.780952ms","remote":"127.0.0.1:50754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"info","ts":"2026-08-13T01:07:22.199449Z","caller":"traceutil/trace.go:171","msg":"trace[442689405] linearizableReadLoop","detail":"{readStateIndex:1284; appliedIndex:1283; }","duration":"447.846851ms","start":"2026-08-13T01:07:21.751578Z","end":"2026-08-13T01:07:22.199425Z","steps":["trace[442689405] 'read index received' (duration: 447.620677ms)","trace[442689405] 'applied index is now lower than readState.Index' (duration: 225.214µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:22.199698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.090956ms","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-08-13T01:07:22.199818Z","caller":"traceutil/trace.go:171","msg":"trace[1277075742] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1234; }","duration":"448.259499ms","start":"2026-08-13T01:07:21.751538Z","end":"2026-08-13T01:07:22.199797Z","steps":["trace[1277075742] 'agreement among raft nodes before linearized reading' (duration: 447.978724ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.199907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:21.751517Z","time spent":"448.367901ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T01:07:22.199481Z","caller":"traceutil/trace.go:171","msg":"trace[1850449773] transaction","detail":"{read_only:false; response_revision:1234; number_of_response:1; }","duration":"683.198504ms","start":"2026-08-13T01:07:21.516257Z","end":"2026-08-13T01:07:22.199455Z","steps":["trace[1850449773] 'process raft request' (duration: 682.98706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.200269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:21.516237Z","time spent":"683.920678ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:22.498077Z","caller":"traceutil/trace.go:171","msg":"trace[773591786] linearizableReadLoop","detail":"{readStateIndex:1285; appliedIndex:1284; }","duration":"298.53262ms","start":"2026-08-13T01:07:22.199517Z","end":"2026-08-13T01:07:22.49805Z","steps":["trace[773591786] 'read index received' (duration: 258.777308ms)","trace[773591786] 'applied index is now lower than readState.Index' (duration: 39.754522ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:22.498226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.261675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T01:07:22.498214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.055626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:22.498256Z","caller":"traceutil/trace.go:171","msg":"trace[1486227626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1235; }","duration":"737.312715ms","start":"2026-08-13T01:07:21.760935Z","end":"2026-08-13T01:07:22.498248Z","steps":["trace[1486227626] 'agreement among raft nodes before linearized reading' (duration: 737.214132ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:22.498264Z","caller":"traceutil/trace.go:171","msg":"trace[5904777] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"673.180029ms","start":"2026-08-13T01:07:21.825073Z","end":"2026-08-13T01:07:22.498253Z","steps":["trace[5904777] 'agreement among raft nodes before linearized reading' (duration: 673.064476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.498281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:21.760926Z","time spent":"737.348876ms","remote":"127.0.0.1:50824","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-08-13T01:07:22.498303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:21.82506Z","time spent":"673.2373ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:07:22.498193Z","caller":"traceutil/trace.go:171","msg":"trace[1498528296] transaction","detail":"{read_only:false; response_revision:1235; number_of_response:1; }","duration":"382.307067ms","start":"2026-08-13T01:07:22.115832Z","end":"2026-08-13T01:07:22.498139Z","steps":["trace[1498528296] 'process raft request' (duration: 342.373722ms)","trace[1498528296] 'compare' (duration: 39.6462ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:22.498392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:22.115816Z","time spent":"382.534803ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:22.498227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.250529ms","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-08-13T01:07:22.498459Z","caller":"traceutil/trace.go:171","msg":"trace[1502793293] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1235; }","duration":"379.503383ms","start":"2026-08-13T01:07:22.118949Z","end":"2026-08-13T01:07:22.498453Z","steps":["trace[1502793293] 'agreement among raft nodes before linearized reading' (duration: 379.174896ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.498483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:22.118944Z","time spent":"379.534134ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T01:07:22.542185Z","caller":"traceutil/trace.go:171","msg":"trace[875182557] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"338.943135ms","start":"2026-08-13T01:07:22.203209Z","end":"2026-08-13T01:07:22.542152Z","steps":["trace[875182557] 'process raft request' (duration: 338.554578ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.542348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:22.203182Z","time spent":"339.081628ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:22.542366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.63084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T01:07:22.542591Z","caller":"traceutil/trace.go:171","msg":"trace[906207069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1236; }","duration":"145.903294ms","start":"2026-08-13T01:07:22.396671Z","end":"2026-08-13T01:07:22.542574Z","steps":["trace[906207069] 'agreement among raft nodes before linearized reading' (duration: 145.462666ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.543049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.518105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-13T01:07:22.543118Z","caller":"traceutil/trace.go:171","msg":"trace[1595501177] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"166.650738ms","start":"2026-08-13T01:07:22.376452Z","end":"2026-08-13T01:07:22.543103Z","steps":["trace[1595501177] 'agreement among raft nodes before linearized reading' (duration: 166.26619ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.543264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.808314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:22.543393Z","caller":"traceutil/trace.go:171","msg":"trace[412844240] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1236; }","duration":"196.398846ms","start":"2026-08-13T01:07:22.346979Z","end":"2026-08-13T01:07:22.543378Z","steps":["trace[412844240] 'agreement among raft nodes before linearized reading' (duration: 195.822234ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:22.660256Z","caller":"traceutil/trace.go:171","msg":"trace[1833051133] linearizableReadLoop","detail":"{readStateIndex:1288; appliedIndex:1286; }","duration":"118.123285ms","start":"2026-08-13T01:07:22.54211Z","end":"2026-08-13T01:07:22.660233Z","steps":["trace[1833051133] 'read index received' (duration: 58.218061ms)","trace[1833051133] 'applied index is now lower than readState.Index' (duration: 59.904224ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:22.660303Z","caller":"traceutil/trace.go:171","msg":"trace[2096953204] transaction","detail":"{read_only:false; response_revision:1237; number_of_response:1; }","duration":"157.96075ms","start":"2026-08-13T01:07:22.502316Z","end":"2026-08-13T01:07:22.660277Z","steps":["trace[2096953204] 'process raft request' (duration: 98.024855ms)","trace[2096953204] 'compare' (duration: 59.720211ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:22.660474Z","caller":"traceutil/trace.go:171","msg":"trace[560400055] transaction","detail":"{read_only:false; response_revision:1238; number_of_response:1; }","duration":"158.04367ms","start":"2026-08-13T01:07:22.502409Z","end":"2026-08-13T01:07:22.660453Z","steps":["trace[560400055] 'process raft request' (duration: 157.781725ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.660585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.154611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:22.660623Z","caller":"traceutil/trace.go:171","msg":"trace[797101624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1238; }","duration":"161.224612ms","start":"2026-08-13T01:07:22.49939Z","end":"2026-08-13T01:07:22.660615Z","steps":["trace[797101624] 'agreement among raft nodes before linearized reading' (duration: 161.12037ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:22.703839Z","caller":"traceutil/trace.go:171","msg":"trace[329158815] transaction","detail":"{read_only:false; response_revision:1239; number_of_response:1; }","duration":"157.043511ms","start":"2026-08-13T01:07:22.546779Z","end":"2026-08-13T01:07:22.703822Z","steps":["trace[329158815] 'process raft request' (duration: 156.874398ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:22.704135Z","caller":"traceutil/trace.go:171","msg":"trace[1409835952] transaction","detail":"{read_only:false; response_revision:1240; number_of_response:1; }","duration":"155.461961ms","start":"2026-08-13T01:07:22.548534Z","end":"2026-08-13T01:07:22.703996Z","steps":["trace[1409835952] 'process raft request' (duration: 155.236307ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:22.9491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.93825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.38\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-13T01:07:22.949193Z","caller":"traceutil/trace.go:171","msg":"trace[8520039] range","detail":"{range_begin:/registry/masterleases/199.204.45.38; range_end:; response_count:1; response_revision:1240; }","duration":"157.080933ms","start":"2026-08-13T01:07:22.792092Z","end":"2026-08-13T01:07:22.949173Z","steps":["trace[8520039] 'range keys from in-memory index tree' (duration: 156.419999ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.261227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.293683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T01:07:24.261415Z","caller":"traceutil/trace.go:171","msg":"trace[1635909079] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"969.284851ms","start":"2026-08-13T01:07:23.292107Z","end":"2026-08-13T01:07:24.261392Z","steps":["trace[1635909079] 'process raft request' (duration: 969.21894ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.261508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:23.292091Z","time spent":"969.389053ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:24.261717Z","caller":"traceutil/trace.go:171","msg":"trace[1086660165] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"972.403122ms","start":"2026-08-13T01:07:23.289295Z","end":"2026-08-13T01:07:24.261699Z","steps":["trace[1086660165] 'read index received' (duration: 264.623071ms)","trace[1086660165] 'applied index is now lower than readState.Index' (duration: 707.777191ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:24.261818Z","caller":"traceutil/trace.go:171","msg":"trace[1877456039] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"982.261683ms","start":"2026-08-13T01:07:23.279545Z","end":"2026-08-13T01:07:24.261807Z","steps":["trace[1877456039] 'process raft request' (duration: 274.331789ms)","trace[1877456039] 'compare' (duration: 707.18167ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:24.261883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:23.279524Z","time spent":"982.318464ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-13T01:07:24.261933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.706284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:24.261972Z","caller":"traceutil/trace.go:171","msg":"trace[1267783242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1245; }","duration":"436.804996ms","start":"2026-08-13T01:07:23.825153Z","end":"2026-08-13T01:07:24.261958Z","steps":["trace[1267783242] 'agreement among raft nodes before linearized reading' (duration: 436.692114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.262001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:23.825134Z","time spent":"436.860477ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-13T01:07:24.262037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"972.729338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-08-13T01:07:24.262112Z","caller":"traceutil/trace.go:171","msg":"trace[873294147] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1245; }","duration":"972.846471ms","start":"2026-08-13T01:07:23.289248Z","end":"2026-08-13T01:07:24.262095Z","steps":["trace[873294147] 'agreement among raft nodes before linearized reading' (duration: 972.590956ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.262151Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:23.28923Z","time spent":"972.913112ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":494,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-08-13T01:07:24.905739Z","caller":"traceutil/trace.go:171","msg":"trace[1340584538] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"639.016585ms","start":"2026-08-13T01:07:24.266696Z","end":"2026-08-13T01:07:24.905713Z","steps":["trace[1340584538] 'process raft request' (duration: 558.417709ms)","trace[1340584538] 'compare' (duration: 80.499515ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:24.905871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:24.266669Z","time spent":"639.136297ms","remote":"127.0.0.1:50656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T01:07:24.953558Z","caller":"traceutil/trace.go:171","msg":"trace[1251135414] linearizableReadLoop","detail":"{readStateIndex:1300; appliedIndex:1296; }","duration":"407.509027ms","start":"2026-08-13T01:07:24.54603Z","end":"2026-08-13T01:07:24.953539Z","steps":["trace[1251135414] 'read index received' (duration: 279.109822ms)","trace[1251135414] 'applied index is now lower than readState.Index' (duration: 128.398565ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:24.953816Z","caller":"traceutil/trace.go:171","msg":"trace[2087997198] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"686.503967ms","start":"2026-08-13T01:07:24.267301Z","end":"2026-08-13T01:07:24.953805Z","steps":["trace[2087997198] 'process raft request' (duration: 686.089849ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.95394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:24.26728Z","time spent":"686.562059ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T01:07:24.953992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.950965ms","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-08-13T01:07:24.954041Z","caller":"traceutil/trace.go:171","msg":"trace[1978170470] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1249; }","duration":"408.033266ms","start":"2026-08-13T01:07:24.545994Z","end":"2026-08-13T01:07:24.954027Z","steps":["trace[1978170470] 'agreement among raft nodes before linearized reading' (duration: 407.842383ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.954046Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.901632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T01:07:24.954075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:24.545978Z","time spent":"408.089609ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-13T01:07:24.954094Z","caller":"traceutil/trace.go:171","msg":"trace[1210466176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1249; }","duration":"289.985405ms","start":"2026-08-13T01:07:24.664096Z","end":"2026-08-13T01:07:24.954082Z","steps":["trace[1210466176] 'agreement among raft nodes before linearized reading' (duration: 289.827911ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:24.954105Z","caller":"traceutil/trace.go:171","msg":"trace[608771903] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"506.739765ms","start":"2026-08-13T01:07:24.447357Z","end":"2026-08-13T01:07:24.954097Z","steps":["trace[608771903] 'process raft request' (duration: 506.127393ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.954146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:24.447324Z","time spent":"506.801796ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:24.954189Z","caller":"traceutil/trace.go:171","msg":"trace[187079898] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"450.92826ms","start":"2026-08-13T01:07:24.50325Z","end":"2026-08-13T01:07:24.954178Z","steps":["trace[187079898] 'process raft request' (duration: 450.261306ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.954232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.214664ms","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-08-13T01:07:24.954241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.366966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-13T01:07:24.954245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:24.503228Z","time spent":"450.981702ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:24.954265Z","caller":"traceutil/trace.go:171","msg":"trace[892870933] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1249; }","duration":"246.424597ms","start":"2026-08-13T01:07:24.707834Z","end":"2026-08-13T01:07:24.954259Z","steps":["trace[892870933] 'agreement among raft nodes before linearized reading' (duration: 246.185622ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:24.954264Z","caller":"traceutil/trace.go:171","msg":"trace[1386704989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1249; }","duration":"246.262954ms","start":"2026-08-13T01:07:24.707993Z","end":"2026-08-13T01:07:24.954256Z","steps":["trace[1386704989] 'agreement among raft nodes before linearized reading' (duration: 246.180873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:24.954249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.090167ms","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-08-13T01:07:24.954336Z","caller":"traceutil/trace.go:171","msg":"trace[722665137] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1249; }","duration":"290.211079ms","start":"2026-08-13T01:07:24.664097Z","end":"2026-08-13T01:07:24.954308Z","steps":["trace[722665137] 'agreement among raft nodes before linearized reading' (duration: 289.953135ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:25.168848Z","caller":"traceutil/trace.go:171","msg":"trace[1902245082] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"210.50379ms","start":"2026-08-13T01:07:24.958313Z","end":"2026-08-13T01:07:25.168817Z","steps":["trace[1902245082] 'process raft request' (duration: 121.212225ms)","trace[1902245082] 'compare' (duration: 89.076831ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:25.168885Z","caller":"traceutil/trace.go:171","msg":"trace[92972280] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"209.672044ms","start":"2026-08-13T01:07:24.959189Z","end":"2026-08-13T01:07:25.168861Z","steps":["trace[92972280] 'process raft request' (duration: 209.608103ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:25.169019Z","caller":"traceutil/trace.go:171","msg":"trace[1481664643] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"208.334718ms","start":"2026-08-13T01:07:24.960642Z","end":"2026-08-13T01:07:25.168976Z","steps":["trace[1481664643] 'process raft request' (duration: 208.203656ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:25.169065Z","caller":"traceutil/trace.go:171","msg":"trace[1658915802] transaction","detail":"{read_only:false; response_revision:1251; number_of_response:1; }","duration":"210.398187ms","start":"2026-08-13T01:07:24.958656Z","end":"2026-08-13T01:07:25.169054Z","steps":["trace[1658915802] 'process raft request' (duration: 210.069421ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:25.169143Z","caller":"traceutil/trace.go:171","msg":"trace[721181138] transaction","detail":"{read_only:false; response_revision:1252; number_of_response:1; }","duration":"210.054461ms","start":"2026-08-13T01:07:24.959082Z","end":"2026-08-13T01:07:25.169137Z","steps":["trace[721181138] 'process raft request' (duration: 209.686164ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.183046Z","caller":"traceutil/trace.go:171","msg":"trace[1652360277] linearizableReadLoop","detail":"{readStateIndex:1306; appliedIndex:1305; }","duration":"154.871848ms","start":"2026-08-13T01:07:27.028152Z","end":"2026-08-13T01:07:27.183024Z","steps":["trace[1652360277] 'read index received' (duration: 154.654644ms)","trace[1652360277] 'applied index is now lower than readState.Index' (duration: 216.034µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:27.183156Z","caller":"traceutil/trace.go:171","msg":"trace[1117572780] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"222.925492ms","start":"2026-08-13T01:07:26.960202Z","end":"2026-08-13T01:07:27.183128Z","steps":["trace[1117572780] 'process raft request' (duration: 222.678156ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:27.183205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.040882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:27.183419Z","caller":"traceutil/trace.go:171","msg":"trace[1525883098] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1255; }","duration":"155.309867ms","start":"2026-08-13T01:07:27.028095Z","end":"2026-08-13T01:07:27.183405Z","steps":["trace[1525883098] 'agreement among raft nodes before linearized reading' (duration: 155.063233ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.372774Z","caller":"traceutil/trace.go:171","msg":"trace[1819829212] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"184.880062ms","start":"2026-08-13T01:07:27.187871Z","end":"2026-08-13T01:07:27.372751Z","steps":["trace[1819829212] 'process raft request' (duration: 174.772126ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.372812Z","caller":"traceutil/trace.go:171","msg":"trace[1228944304] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"183.322622ms","start":"2026-08-13T01:07:27.189461Z","end":"2026-08-13T01:07:27.372784Z","steps":["trace[1228944304] 'process raft request' (duration: 182.985835ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.372878Z","caller":"traceutil/trace.go:171","msg":"trace[751636195] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"184.622637ms","start":"2026-08-13T01:07:27.188231Z","end":"2026-08-13T01:07:27.372853Z","steps":["trace[751636195] 'process raft request' (duration: 184.025196ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.3729Z","caller":"traceutil/trace.go:171","msg":"trace[1312219416] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"183.115588ms","start":"2026-08-13T01:07:27.189758Z","end":"2026-08-13T01:07:27.372873Z","steps":["trace[1312219416] 'process raft request' (duration: 182.727371ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:27.372969Z","caller":"traceutil/trace.go:171","msg":"trace[2105560984] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"184.678399ms","start":"2026-08-13T01:07:27.18828Z","end":"2026-08-13T01:07:27.372959Z","steps":["trace[2105560984] 'process raft request' (duration: 184.058567ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:28.912905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.913399ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-08-13T01:07:29.125249Z","caller":"traceutil/trace.go:171","msg":"trace[82435785] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"300.843195ms","start":"2026-08-13T01:07:28.824385Z","end":"2026-08-13T01:07:29.125228Z","steps":["trace[82435785] 'read index received' (duration: 300.707232ms)","trace[82435785] 'applied index is now lower than readState.Index' (duration: 135.013µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:07:29.125431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.960407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:07:29.125462Z","caller":"traceutil/trace.go:171","msg":"trace[1429373663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"301.12011ms","start":"2026-08-13T01:07:28.824334Z","end":"2026-08-13T01:07:29.125454Z","steps":["trace[1429373663] 'agreement among raft nodes before linearized reading' (duration: 300.970067ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:29.125587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:28.824311Z","time spent":"301.266913ms","remote":"127.0.0.1:50546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T01:07:29.1256Z","caller":"traceutil/trace.go:171","msg":"trace[117936129] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"792.906184ms","start":"2026-08-13T01:07:28.332661Z","end":"2026-08-13T01:07:29.125567Z","steps":["trace[117936129] 'process raft request' (duration: 792.429655ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:29.125758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T01:07:28.33264Z","time spent":"793.029577ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T01:07:43.553576Z","caller":"traceutil/trace.go:171","msg":"trace[1387926072] linearizableReadLoop","detail":"{readStateIndex:1413; appliedIndex:1412; }","duration":"105.267056ms","start":"2026-08-13T01:07:43.448293Z","end":"2026-08-13T01:07:43.55356Z","steps":["trace[1387926072] 'read index received' (duration: 2.822855ms)","trace[1387926072] 'applied index is now lower than readState.Index' (duration: 102.443661ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:07:43.553648Z","caller":"traceutil/trace.go:171","msg":"trace[1564058598] transaction","detail":"{read_only:false; response_revision:1358; number_of_response:1; }","duration":"106.181903ms","start":"2026-08-13T01:07:43.447423Z","end":"2026-08-13T01:07:43.553605Z","steps":["trace[1564058598] 'process raft request' (duration: 106.03973ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.553741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.435479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-13T01:07:43.553765Z","caller":"traceutil/trace.go:171","msg":"trace[974694536] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1358; }","duration":"105.4951ms","start":"2026-08-13T01:07:43.448261Z","end":"2026-08-13T01:07:43.553757Z","steps":["trace[974694536] 'agreement among raft nodes before linearized reading' (duration: 105.391688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:07:43.55388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.430589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-13T01:07:43.553914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.841509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T01:07:43.553967Z","caller":"traceutil/trace.go:171","msg":"trace[1317698058] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1358; }","duration":"104.550611ms","start":"2026-08-13T01:07:43.449401Z","end":"2026-08-13T01:07:43.553951Z","steps":["trace[1317698058] 'agreement among raft nodes before linearized reading' (duration: 104.294767ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:07:43.554003Z","caller":"traceutil/trace.go:171","msg":"trace[362155020] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1358; }","duration":"102.985671ms","start":"2026-08-13T01:07:43.450997Z","end":"2026-08-13T01:07:43.553983Z","steps":["trace[362155020] 'agreement among raft nodes before linearized reading' (duration: 102.730016ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:16.828807Z","caller":"traceutil/trace.go:171","msg":"trace[1208776133] transaction","detail":"{read_only:false; response_revision:1511; number_of_response:1; }","duration":"103.778762ms","start":"2026-08-13T01:08:16.724993Z","end":"2026-08-13T01:08:16.828772Z","steps":["trace[1208776133] 'process raft request' (duration: 103.592888ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:17.644224Z","caller":"traceutil/trace.go:171","msg":"trace[1422260013] transaction","detail":"{read_only:false; response_revision:1513; number_of_response:1; }","duration":"154.515842ms","start":"2026-08-13T01:08:17.489677Z","end":"2026-08-13T01:08:17.644193Z","steps":["trace[1422260013] 'process raft request' (duration: 103.946596ms)","trace[1422260013] 'compare' (duration: 50.449793ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:19.726158Z","caller":"traceutil/trace.go:171","msg":"trace[1683960588] transaction","detail":"{read_only:false; response_revision:1521; number_of_response:1; }","duration":"172.139204ms","start":"2026-08-13T01:08:19.553964Z","end":"2026-08-13T01:08:19.726103Z","steps":["trace[1683960588] 'process raft request' (duration: 76.137179ms)","trace[1683960588] 'compare' (duration: 95.717868ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:19.969151Z","caller":"traceutil/trace.go:171","msg":"trace[909128464] transaction","detail":"{read_only:false; response_revision:1522; number_of_response:1; }","duration":"239.67028ms","start":"2026-08-13T01:08:19.729443Z","end":"2026-08-13T01:08:19.969113Z","steps":["trace[909128464] 'process raft request' (duration: 200.285706ms)","trace[909128464] 'compare' (duration: 39.102459ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:19.969318Z","caller":"traceutil/trace.go:171","msg":"trace[1037575461] linearizableReadLoop","detail":"{readStateIndex:1584; appliedIndex:1583; }","duration":"183.686441ms","start":"2026-08-13T01:08:19.785591Z","end":"2026-08-13T01:08:19.969278Z","steps":["trace[1037575461] 'read index received' (duration: 144.119774ms)","trace[1037575461] 'applied index is now lower than readState.Index' (duration: 39.559957ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:08:19.969936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.307334ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-08-13T01:08:19.970559Z","caller":"traceutil/trace.go:171","msg":"trace[1230386872] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1522; }","duration":"185.000576ms","start":"2026-08-13T01:08:19.785537Z","end":"2026-08-13T01:08:19.970538Z","steps":["trace[1230386872] 'agreement among raft nodes before linearized reading' (duration: 184.252253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.97001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.942241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-13T01:08:19.97072Z","caller":"traceutil/trace.go:171","msg":"trace[110935574] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1522; }","duration":"162.674684ms","start":"2026-08-13T01:08:19.808036Z","end":"2026-08-13T01:08:19.970711Z","steps":["trace[110935574] 'agreement among raft nodes before linearized reading' (duration: 161.789608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:19.970082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.912667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T01:08:19.97083Z","caller":"traceutil/trace.go:171","msg":"trace[1089353966] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1522; }","duration":"147.700681ms","start":"2026-08-13T01:08:19.823107Z","end":"2026-08-13T01:08:19.970808Z","steps":["trace[1089353966] 'agreement among raft nodes before linearized reading' (duration: 146.893626ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:22.202576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.293903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-08-13T01:08:22.202643Z","caller":"traceutil/trace.go:171","msg":"trace[2029798123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1533; }","duration":"138.394345ms","start":"2026-08-13T01:08:22.064232Z","end":"2026-08-13T01:08:22.202627Z","steps":["trace[2029798123] 'range keys from in-memory index tree' (duration: 138.09807ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:22.202653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.310294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-13T01:08:22.202724Z","caller":"traceutil/trace.go:171","msg":"trace[1998405083] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1533; }","duration":"138.408956ms","start":"2026-08-13T01:08:22.064298Z","end":"2026-08-13T01:08:22.202707Z","steps":["trace[1998405083] 'range keys from in-memory index tree' (duration: 138.190181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:08:22.202754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.883736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-13T01:08:22.202841Z","caller":"traceutil/trace.go:171","msg":"trace[636075302] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1533; }","duration":"138.007438ms","start":"2026-08-13T01:08:22.064815Z","end":"2026-08-13T01:08:22.202822Z","steps":["trace[636075302] 'range keys from in-memory index tree' (duration: 137.708192ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:37.884904Z","caller":"traceutil/trace.go:171","msg":"trace[1550263508] transaction","detail":"{read_only:false; response_revision:1605; number_of_response:1; }","duration":"159.399383ms","start":"2026-08-13T01:08:37.725471Z","end":"2026-08-13T01:08:37.884871Z","steps":["trace[1550263508] 'process raft request' (duration: 68.379283ms)","trace[1550263508] 'compare' (duration: 90.890678ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:08:48.757795Z","caller":"traceutil/trace.go:171","msg":"trace[651357395] transaction","detail":"{read_only:false; response_revision:1656; number_of_response:1; }","duration":"193.12393ms","start":"2026-08-13T01:08:48.564647Z","end":"2026-08-13T01:08:48.757771Z","steps":["trace[651357395] 'process raft request' (duration: 193.062169ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:08:48.757969Z","caller":"traceutil/trace.go:171","msg":"trace[861043267] transaction","detail":"{read_only:false; response_revision:1655; number_of_response:1; }","duration":"195.788851ms","start":"2026-08-13T01:08:48.562084Z","end":"2026-08-13T01:08:48.757873Z","steps":["trace[861043267] 'process raft request' (duration: 187.579506ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:14.384019Z","caller":"traceutil/trace.go:171","msg":"trace[586748856] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2144; }","duration":"130.692381ms","start":"2026-08-13T01:10:14.253302Z","end":"2026-08-13T01:10:14.383994Z","steps":["trace[586748856] 'read index received' (duration: 24.297328ms)","trace[586748856] 'applied index is now lower than readState.Index' (duration: 106.393693ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:14.384105Z","caller":"traceutil/trace.go:171","msg":"trace[1755110904] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"130.882204ms","start":"2026-08-13T01:10:14.253178Z","end":"2026-08-13T01:10:14.38406Z","steps":["trace[1755110904] 'process raft request' (duration: 96.560581ms)","trace[1755110904] 'compare' (duration: 34.14413ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T01:10:14.384275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.954946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/attachdetach-controller\" ","response":"range_response_count:1 size:212"} {"level":"info","ts":"2026-08-13T01:10:14.384402Z","caller":"traceutil/trace.go:171","msg":"trace[1573816713] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/attachdetach-controller; range_end:; response_count:1; response_revision:2060; }","duration":"131.125729ms","start":"2026-08-13T01:10:14.253242Z","end":"2026-08-13T01:10:14.384367Z","steps":["trace[1573816713] 'agreement among raft nodes before linearized reading' (duration: 130.841413ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:14.569122Z","caller":"traceutil/trace.go:171","msg":"trace[1525521001] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"119.20739ms","start":"2026-08-13T01:10:14.449882Z","end":"2026-08-13T01:10:14.569089Z","steps":["trace[1525521001] 'process raft request' (duration: 83.642673ms)","trace[1525521001] 'compare' (duration: 35.306232ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T01:10:15.124595Z","caller":"traceutil/trace.go:171","msg":"trace[2117837744] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"150.278892ms","start":"2026-08-13T01:10:14.974294Z","end":"2026-08-13T01:10:15.124573Z","steps":["trace[2117837744] 'process raft request' (duration: 150.15412ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T01:10:15.154179Z","caller":"traceutil/trace.go:171","msg":"trace[178579617] transaction","detail":"{read_only:false; response_revision:2066; number_of_response:1; }","duration":"174.191522ms","start":"2026-08-13T01:10:14.979962Z","end":"2026-08-13T01:10:15.154153Z","steps":["trace[178579617] 'process raft request' (duration: 173.957238ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T01:10:23.443461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.047944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-13T01:10:23.443552Z","caller":"traceutil/trace.go:171","msg":"trace[1100576930] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2110; }","duration":"101.210697ms","start":"2026-08-13T01:10:23.342322Z","end":"2026-08-13T01:10:23.443533Z","steps":["trace[1100576930] 'range keys from in-memory index tree' (duration: 100.85218ms)"],"step_count":1}