{"level":"warn","ts":"2026-07-14T17:45:58.060685Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:45:58.060779Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.222:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.222:2380","--initial-cluster=instance=https://199.19.213.222:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.222:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.222:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T17:45:58.06085Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T17:45:58.060865Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-14T17:45:58.060891Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T17:45:58.061676Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"]} {"level":"info","ts":"2026-07-14T17:45:58.061771Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.222:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T17:45:58.064126Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.099553ms"} {"level":"info","ts":"2026-07-14T17:45:58.069077Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"b78f8b1ca6063edc","cluster-id":"eff609df5818f5a9"} {"level":"info","ts":"2026-07-14T17:45:58.069136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T17:45:58.069157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 0"} {"level":"info","ts":"2026-07-14T17:45:58.069168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft b78f8b1ca6063edc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T17:45:58.069178Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became follower at term 1"} {"level":"info","ts":"2026-07-14T17:45:58.069206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"warn","ts":"2026-07-14T17:45:58.073568Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T17:45:58.074712Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T17:45:58.075773Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T17:45:58.077536Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"b78f8b1ca6063edc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T17:45:58.077686Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"b78f8b1ca6063edc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T17:45:58.077805Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:45:58.079562Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:45:58.079595Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T17:45:58.080031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc switched to configuration voters=(13226943585771208412)"} {"level":"info","ts":"2026-07-14T17:45:58.08057Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","added-peer-id":"b78f8b1ca6063edc","added-peer-peer-urls":["https://199.19.213.222:2380"]} {"level":"info","ts":"2026-07-14T17:45:58.084417Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T17:45:58.084589Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-14T17:45:58.084628Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T17:45:58.084632Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.222:2380"} {"level":"info","ts":"2026-07-14T17:45:58.084593Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"b78f8b1ca6063edc","initial-advertise-peer-urls":["https://199.19.213.222:2380"],"listen-peer-urls":["https://199.19.213.222:2380"],"advertise-client-urls":["https://199.19.213.222:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.222:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T17:45:58.770345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T17:45:58.770401Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T17:45:58.770438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgPreVoteResp from b78f8b1ca6063edc at term 1"} {"level":"info","ts":"2026-07-14T17:45:58.770456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became candidate at term 2"} {"level":"info","ts":"2026-07-14T17:45:58.77047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc received MsgVoteResp from b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-14T17:45:58.770486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"b78f8b1ca6063edc became leader at term 2"} {"level":"info","ts":"2026-07-14T17:45:58.770502Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: b78f8b1ca6063edc elected leader b78f8b1ca6063edc at term 2"} {"level":"info","ts":"2026-07-14T17:45:58.771493Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"b78f8b1ca6063edc","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.222:2379]}","request-path":"/0/members/b78f8b1ca6063edc/attributes","cluster-id":"eff609df5818f5a9","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T17:45:58.771748Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:45:58.772576Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:45:58.772897Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T17:45:58.77308Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T17:45:58.77314Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T17:45:58.773186Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"eff609df5818f5a9","local-member-id":"b78f8b1ca6063edc","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:45:58.773254Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:45:58.773273Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T17:45:58.775548Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.222:2379"} {"level":"info","ts":"2026-07-14T17:45:58.777824Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T17:46:01.114851Z","caller":"traceutil/trace.go:171","msg":"trace[569666703] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"185.131014ms","start":"2026-07-14T17:46:00.929684Z","end":"2026-07-14T17:46:01.114815Z","steps":["trace[569666703] 'read index received' (duration: 184.946419ms)","trace[569666703] 'applied index is now lower than readState.Index' (duration: 179.815µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:46:01.114959Z","caller":"traceutil/trace.go:171","msg":"trace[1275113843] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"186.718873ms","start":"2026-07-14T17:46:00.928185Z","end":"2026-07-14T17:46:01.114904Z","steps":["trace[1275113843] 'process raft request' (duration: 186.506038ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:01.115066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.345059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:46:01.115594Z","caller":"traceutil/trace.go:171","msg":"trace[1431188644] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:72; }","duration":"185.711528ms","start":"2026-07-14T17:46:00.92967Z","end":"2026-07-14T17:46:01.115382Z","steps":["trace[1431188644] 'agreement among raft nodes before linearized reading' (duration: 185.288238ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:01.621673Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670567347631582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:46:02.122518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670567347631582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T17:46:02.132793Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.012005549s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T17:46:02.133196Z","caller":"traceutil/trace.go:171","msg":"trace[2124208076] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"1.012478661s","start":"2026-07-14T17:46:01.120692Z","end":"2026-07-14T17:46:02.13317Z","steps":["trace[2124208076] 'process raft request' (duration: 1.012307507s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:02.133665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:01.120671Z","time spent":"1.012576343s","remote":"127.0.0.1:45770","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:46:02.141621Z","caller":"traceutil/trace.go:171","msg":"trace[1420534299] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"1.020772561s","start":"2026-07-14T17:46:01.120831Z","end":"2026-07-14T17:46:02.141604Z","steps":["trace[1420534299] 'read index received' (duration: 1.012232915s)","trace[1420534299] 'applied index is now lower than readState.Index' (duration: 8.538696ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:46:02.141644Z","caller":"traceutil/trace.go:171","msg":"trace[389013292] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"141.363618ms","start":"2026-07-14T17:46:02.000268Z","end":"2026-07-14T17:46:02.141632Z","steps":["trace[389013292] 'process raft request' (duration: 141.247956ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:02.141876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.021013146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:46:02.141975Z","caller":"traceutil/trace.go:171","msg":"trace[1407246147] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:74; }","duration":"1.02116439s","start":"2026-07-14T17:46:01.120785Z","end":"2026-07-14T17:46:02.141949Z","steps":["trace[1407246147] 'agreement among raft nodes before linearized reading' (duration: 1.020902194s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:02.142018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:01.120767Z","time spent":"1.021241093s","remote":"127.0.0.1:45764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:aggregate-to-admin\" "} {"level":"warn","ts":"2026-07-14T17:46:02.144185Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.631187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T17:46:02.144293Z","caller":"traceutil/trace.go:171","msg":"trace[1440069431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:75; }","duration":"113.798841ms","start":"2026-07-14T17:46:02.03047Z","end":"2026-07-14T17:46:02.144269Z","steps":["trace[1440069431] 'agreement among raft nodes before linearized reading' (duration: 113.585086ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.429928Z","caller":"traceutil/trace.go:171","msg":"trace[1204005877] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"131.60413ms","start":"2026-07-14T17:46:34.298293Z","end":"2026-07-14T17:46:34.429897Z","steps":["trace[1204005877] 'process raft request' (duration: 131.398634ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.43574Z","caller":"traceutil/trace.go:171","msg":"trace[671705753] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"123.683871ms","start":"2026-07-14T17:46:34.312033Z","end":"2026-07-14T17:46:34.435717Z","steps":["trace[671705753] 'process raft request' (duration: 123.453375ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436036Z","caller":"traceutil/trace.go:171","msg":"trace[1511287203] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:542; }","duration":"117.39303ms","start":"2026-07-14T17:46:34.318619Z","end":"2026-07-14T17:46:34.436012Z","steps":["trace[1511287203] 'read index received' (duration: 111.604224ms)","trace[1511287203] 'applied index is now lower than readState.Index' (duration: 5.788096ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:46:34.436062Z","caller":"traceutil/trace.go:171","msg":"trace[676956478] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"132.116333ms","start":"2026-07-14T17:46:34.303933Z","end":"2026-07-14T17:46:34.43605Z","steps":["trace[676956478] 'process raft request' (duration: 131.432995ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436152Z","caller":"traceutil/trace.go:171","msg":"trace[383674630] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"131.322213ms","start":"2026-07-14T17:46:34.30476Z","end":"2026-07-14T17:46:34.436082Z","steps":["trace[383674630] 'process raft request' (duration: 130.672937ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436384Z","caller":"traceutil/trace.go:171","msg":"trace[389903915] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"121.242567ms","start":"2026-07-14T17:46:34.315127Z","end":"2026-07-14T17:46:34.43637Z","steps":["trace[389903915] 'process raft request' (duration: 120.407126ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436513Z","caller":"traceutil/trace.go:171","msg":"trace[926293525] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"120.173801ms","start":"2026-07-14T17:46:34.316324Z","end":"2026-07-14T17:46:34.436498Z","steps":["trace[926293525] 'process raft request' (duration: 119.248398ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436619Z","caller":"traceutil/trace.go:171","msg":"trace[859558982] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"118.1523ms","start":"2026-07-14T17:46:34.318455Z","end":"2026-07-14T17:46:34.436608Z","steps":["trace[859558982] 'process raft request' (duration: 117.151895ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436755Z","caller":"traceutil/trace.go:171","msg":"trace[2061731674] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"104.888673ms","start":"2026-07-14T17:46:34.331848Z","end":"2026-07-14T17:46:34.436736Z","steps":["trace[2061731674] 'process raft request' (duration: 103.838357ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.436796Z","caller":"traceutil/trace.go:171","msg":"trace[1704628269] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"118.230091ms","start":"2026-07-14T17:46:34.318511Z","end":"2026-07-14T17:46:34.436741Z","steps":["trace[1704628269] 'process raft request' (duration: 117.136374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:34.436979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.342715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T17:46:34.437049Z","caller":"traceutil/trace.go:171","msg":"trace[1698994832] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:532; }","duration":"118.434067ms","start":"2026-07-14T17:46:34.318599Z","end":"2026-07-14T17:46:34.437033Z","steps":["trace[1698994832] 'agreement among raft nodes before linearized reading' (duration: 118.292753ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:34.43712Z","caller":"traceutil/trace.go:171","msg":"trace[2053133376] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"105.200201ms","start":"2026-07-14T17:46:34.331907Z","end":"2026-07-14T17:46:34.437107Z","steps":["trace[2053133376] 'process raft request' (duration: 103.824757ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:38.379803Z","caller":"traceutil/trace.go:171","msg":"trace[982263046] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"258.980874ms","start":"2026-07-14T17:46:38.1208Z","end":"2026-07-14T17:46:38.37978Z","steps":["trace[982263046] 'process raft request' (duration: 258.804539ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:38.429257Z","caller":"traceutil/trace.go:171","msg":"trace[1857204085] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"221.109315ms","start":"2026-07-14T17:46:38.208133Z","end":"2026-07-14T17:46:38.429242Z","steps":["trace[1857204085] 'process raft request' (duration: 220.707605ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.429381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.811078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.429561Z","caller":"traceutil/trace.go:171","msg":"trace[1657280377] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:612; }","duration":"189.031382ms","start":"2026-07-14T17:46:38.240516Z","end":"2026-07-14T17:46:38.429547Z","steps":["trace[1657280377] 'agreement among raft nodes before linearized reading' (duration: 188.821687ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:38.429197Z","caller":"traceutil/trace.go:171","msg":"trace[1343362275] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:636; }","duration":"188.617592ms","start":"2026-07-14T17:46:38.240552Z","end":"2026-07-14T17:46:38.429169Z","steps":["trace[1343362275] 'read index received' (duration: 139.020877ms)","trace[1343362275] 'applied index is now lower than readState.Index' (duration: 49.596155ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:46:38.430554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.211687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:46:38.430533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.732621ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.430593Z","caller":"traceutil/trace.go:171","msg":"trace[1233343844] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:612; }","duration":"119.309609ms","start":"2026-07-14T17:46:38.31127Z","end":"2026-07-14T17:46:38.43058Z","steps":["trace[1233343844] 'agreement among raft nodes before linearized reading' (duration: 119.207436ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:38.430631Z","caller":"traceutil/trace.go:171","msg":"trace[1288473621] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:612; }","duration":"189.889575ms","start":"2026-07-14T17:46:38.240715Z","end":"2026-07-14T17:46:38.430604Z","steps":["trace[1288473621] 'agreement among raft nodes before linearized reading' (duration: 189.71694ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.43082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.791304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.430879Z","caller":"traceutil/trace.go:171","msg":"trace[1666931121] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:612; }","duration":"174.880156ms","start":"2026-07-14T17:46:38.255977Z","end":"2026-07-14T17:46:38.430857Z","steps":["trace[1666931121] 'agreement among raft nodes before linearized reading' (duration: 174.800994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.430947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.481431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.430987Z","caller":"traceutil/trace.go:171","msg":"trace[330154815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:612; }","duration":"175.550963ms","start":"2026-07-14T17:46:38.255425Z","end":"2026-07-14T17:46:38.430976Z","steps":["trace[330154815] 'agreement among raft nodes before linearized reading' (duration: 175.484061ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.431058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.571823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.431084Z","caller":"traceutil/trace.go:171","msg":"trace[1665473755] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:612; }","duration":"175.614534ms","start":"2026-07-14T17:46:38.255463Z","end":"2026-07-14T17:46:38.431077Z","steps":["trace[1665473755] 'agreement among raft nodes before linearized reading' (duration: 175.575363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.431122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.062393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T17:46:38.431158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.531886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.43115Z","caller":"traceutil/trace.go:171","msg":"trace[291505311] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:612; }","duration":"119.110654ms","start":"2026-07-14T17:46:38.312031Z","end":"2026-07-14T17:46:38.431142Z","steps":["trace[291505311] 'agreement among raft nodes before linearized reading' (duration: 119.062703ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:38.431188Z","caller":"traceutil/trace.go:171","msg":"trace[847410312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:612; }","duration":"189.587878ms","start":"2026-07-14T17:46:38.241587Z","end":"2026-07-14T17:46:38.431175Z","steps":["trace[847410312] 'agreement among raft nodes before linearized reading' (duration: 189.552007ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:38.431266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.898154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:38.431307Z","caller":"traceutil/trace.go:171","msg":"trace[1571723387] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:612; }","duration":"119.967186ms","start":"2026-07-14T17:46:38.311334Z","end":"2026-07-14T17:46:38.431301Z","steps":["trace[1571723387] 'agreement among raft nodes before linearized reading' (duration: 119.909975ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:40.996497Z","caller":"traceutil/trace.go:171","msg":"trace[1379344377] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"125.713951ms","start":"2026-07-14T17:46:40.870755Z","end":"2026-07-14T17:46:40.996469Z","steps":["trace[1379344377] 'read index received' (duration: 124.989832ms)","trace[1379344377] 'applied index is now lower than readState.Index' (duration: 723.139µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:46:40.996769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.000218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvkr6\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T17:46:40.996833Z","caller":"traceutil/trace.go:171","msg":"trace[507695403] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvkr6; range_end:; response_count:1; response_revision:630; }","duration":"126.108821ms","start":"2026-07-14T17:46:40.870711Z","end":"2026-07-14T17:46:40.99682Z","steps":["trace[507695403] 'agreement among raft nodes before linearized reading' (duration: 125.941937ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:40.997114Z","caller":"traceutil/trace.go:171","msg":"trace[1711366205] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"244.191119ms","start":"2026-07-14T17:46:40.75291Z","end":"2026-07-14T17:46:40.997101Z","steps":["trace[1711366205] 'process raft request' (duration: 243.046069ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:46:41.005169Z","caller":"traceutil/trace.go:171","msg":"trace[671673227] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"125.012974ms","start":"2026-07-14T17:46:40.880134Z","end":"2026-07-14T17:46:41.005147Z","steps":["trace[671673227] 'process raft request' (duration: 124.918571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:41.608038Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4529670567347632754,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:46:41.674638Z","caller":"traceutil/trace.go:171","msg":"trace[571520856] linearizableReadLoop","detail":"{readStateIndex:660; appliedIndex:659; }","duration":"567.373326ms","start":"2026-07-14T17:46:41.107241Z","end":"2026-07-14T17:46:41.674615Z","steps":["trace[571520856] 'read index received' (duration: 553.181337ms)","trace[571520856] 'applied index is now lower than readState.Index' (duration: 14.190759ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:46:41.674773Z","caller":"traceutil/trace.go:171","msg":"trace[1754027235] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"616.690684ms","start":"2026-07-14T17:46:41.05807Z","end":"2026-07-14T17:46:41.674761Z","steps":["trace[1754027235] 'process raft request' (duration: 602.339461ms)","trace[1754027235] 'compare' (duration: 13.634444ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:46:41.674854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:41.058056Z","time spent":"616.748645ms","remote":"127.0.0.1:45646","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T17:46:41.675074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"567.827288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:46:41.675115Z","caller":"traceutil/trace.go:171","msg":"trace[1094141794] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"567.956231ms","start":"2026-07-14T17:46:41.107149Z","end":"2026-07-14T17:46:41.675105Z","steps":["trace[1094141794] 'agreement among raft nodes before linearized reading' (duration: 567.874769ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:41.675147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:41.107129Z","time spent":"568.012452ms","remote":"127.0.0.1:45424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-14T17:46:41.675389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.443789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-14T17:46:41.67547Z","caller":"traceutil/trace.go:171","msg":"trace[1512760976] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"505.579533ms","start":"2026-07-14T17:46:41.16987Z","end":"2026-07-14T17:46:41.67545Z","steps":["trace[1512760976] 'agreement among raft nodes before linearized reading' (duration: 505.347947ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:46:41.67552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:46:41.169849Z","time spent":"505.663035ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T17:47:30.333915Z","caller":"traceutil/trace.go:171","msg":"trace[1629132906] linearizableReadLoop","detail":"{readStateIndex:1042; appliedIndex:1041; }","duration":"315.037116ms","start":"2026-07-14T17:47:30.018849Z","end":"2026-07-14T17:47:30.333886Z","steps":["trace[1629132906] 'read index received' (duration: 314.898302ms)","trace[1629132906] 'applied index is now lower than readState.Index' (duration: 137.754µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:30.333977Z","caller":"traceutil/trace.go:171","msg":"trace[1959036513] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"433.478395ms","start":"2026-07-14T17:47:29.900475Z","end":"2026-07-14T17:47:30.333953Z","steps":["trace[1959036513] 'process raft request' (duration: 433.28163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:30.334122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.273171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-14T17:47:30.334178Z","caller":"traceutil/trace.go:171","msg":"trace[646649547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"315.379276ms","start":"2026-07-14T17:47:30.018775Z","end":"2026-07-14T17:47:30.334154Z","steps":["trace[646649547] 'agreement among raft nodes before linearized reading' (duration: 315.222301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:30.33422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:30.018757Z","time spent":"315.451537ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:47:30.334396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:29.90046Z","time spent":"433.629528ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:47:30.335745Z","caller":"traceutil/trace.go:171","msg":"trace[280437883] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"188.345798ms","start":"2026-07-14T17:47:30.14738Z","end":"2026-07-14T17:47:30.335726Z","steps":["trace[280437883] 'process raft request' (duration: 188.233515ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:51.345247Z","caller":"traceutil/trace.go:171","msg":"trace[2018908026] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"160.481414ms","start":"2026-07-14T17:47:51.184742Z","end":"2026-07-14T17:47:51.345223Z","steps":["trace[2018908026] 'process raft request' (duration: 160.356731ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:52.658664Z","caller":"traceutil/trace.go:171","msg":"trace[758620581] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"218.716887ms","start":"2026-07-14T17:47:52.43992Z","end":"2026-07-14T17:47:52.658637Z","steps":["trace[758620581] 'process raft request' (duration: 207.38209ms)","trace[758620581] 'compare' (duration: 11.235064ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:52.687317Z","caller":"traceutil/trace.go:171","msg":"trace[1368801375] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"245.371912ms","start":"2026-07-14T17:47:52.441926Z","end":"2026-07-14T17:47:52.687298Z","steps":["trace[1368801375] 'process raft request' (duration: 245.28006ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:54.597513Z","caller":"traceutil/trace.go:171","msg":"trace[2132519664] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"153.581948ms","start":"2026-07-14T17:47:54.443905Z","end":"2026-07-14T17:47:54.597487Z","steps":["trace[2132519664] 'process raft request' (duration: 153.441805ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.078829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.774858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:47:55.078968Z","caller":"traceutil/trace.go:171","msg":"trace[1405035584] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1082; }","duration":"231.973904ms","start":"2026-07-14T17:47:54.846957Z","end":"2026-07-14T17:47:55.078931Z","steps":["trace[1405035584] 'range keys from in-memory index tree' (duration: 231.666325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.078971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"415.628474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:47:55.079076Z","caller":"traceutil/trace.go:171","msg":"trace[613638761] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1082; }","duration":"415.784008ms","start":"2026-07-14T17:47:54.663271Z","end":"2026-07-14T17:47:55.079056Z","steps":["trace[613638761] 'range keys from in-memory index tree' (duration: 415.319626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.07912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:54.66325Z","time spent":"415.859939ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T17:47:55.079478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.811426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T17:47:55.079522Z","caller":"traceutil/trace.go:171","msg":"trace[129959085] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1082; }","duration":"219.909808ms","start":"2026-07-14T17:47:54.8596Z","end":"2026-07-14T17:47:55.07951Z","steps":["trace[129959085] 'range keys from in-memory index tree' (duration: 219.56749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.079626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.638714ms","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-07-14T17:47:55.079724Z","caller":"traceutil/trace.go:171","msg":"trace[1314773073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1082; }","duration":"325.825989ms","start":"2026-07-14T17:47:54.753878Z","end":"2026-07-14T17:47:55.079704Z","steps":["trace[1314773073] 'range keys from in-memory index tree' (duration: 325.442159ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.079793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:54.753864Z","time spent":"325.920091ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T17:47:55.079737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.886161ms","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-07-14T17:47:55.079913Z","caller":"traceutil/trace.go:171","msg":"trace[1082831100] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1082; }","duration":"388.094817ms","start":"2026-07-14T17:47:54.69181Z","end":"2026-07-14T17:47:55.079905Z","steps":["trace[1082831100] 'range keys from in-memory index tree' (duration: 387.750687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.079939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:47:54.691798Z","time spent":"388.135108ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T17:47:55.242719Z","caller":"traceutil/trace.go:171","msg":"trace[450387168] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"158.532924ms","start":"2026-07-14T17:47:55.084159Z","end":"2026-07-14T17:47:55.242692Z","steps":["trace[450387168] 'process raft request' (duration: 158.384361ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:55.263595Z","caller":"traceutil/trace.go:171","msg":"trace[206983745] transaction","detail":"{read_only:false; response_revision:1084; number_of_response:1; }","duration":"178.849667ms","start":"2026-07-14T17:47:55.084718Z","end":"2026-07-14T17:47:55.263568Z","steps":["trace[206983745] 'process raft request' (duration: 178.638902ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:55.26391Z","caller":"traceutil/trace.go:171","msg":"trace[1184486412] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"178.771097ms","start":"2026-07-14T17:47:55.085125Z","end":"2026-07-14T17:47:55.263896Z","steps":["trace[1184486412] 'process raft request' (duration: 178.406636ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:47:55.263911Z","caller":"traceutil/trace.go:171","msg":"trace[191754985] linearizableReadLoop","detail":"{readStateIndex:1131; appliedIndex:1127; }","duration":"158.337449ms","start":"2026-07-14T17:47:55.105547Z","end":"2026-07-14T17:47:55.263885Z","steps":["trace[191754985] 'read index received' (duration: 136.998099ms)","trace[191754985] 'applied index is now lower than readState.Index' (duration: 21.3381ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:47:55.264022Z","caller":"traceutil/trace.go:171","msg":"trace[1296081502] transaction","detail":"{read_only:false; response_revision:1086; number_of_response:1; }","duration":"178.815927ms","start":"2026-07-14T17:47:55.085196Z","end":"2026-07-14T17:47:55.264012Z","steps":["trace[1296081502] 'process raft request' (duration: 178.568111ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:47:55.264036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.464592ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:47:55.264072Z","caller":"traceutil/trace.go:171","msg":"trace[178817949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1086; }","duration":"158.554035ms","start":"2026-07-14T17:47:55.105507Z","end":"2026-07-14T17:47:55.264061Z","steps":["trace[178817949] 'agreement among raft nodes before linearized reading' (duration: 158.474113ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:28.607166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.804593ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-07-14T17:48:29.425019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.653609ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:48:29.425138Z","caller":"traceutil/trace.go:171","msg":"trace[26457787] transaction","detail":"{read_only:false; response_revision:1271; number_of_response:1; }","duration":"435.820575ms","start":"2026-07-14T17:48:28.989297Z","end":"2026-07-14T17:48:29.425118Z","steps":["trace[26457787] 'process raft request' (duration: 201.987331ms)","trace[26457787] 'compare' (duration: 233.323101ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:48:29.425222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:28.989281Z","time spent":"435.893806ms","remote":"127.0.0.1:45494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T17:48:29.428567Z","caller":"traceutil/trace.go:171","msg":"trace[602333125] transaction","detail":"{read_only:false; response_revision:1272; number_of_response:1; }","duration":"435.632299ms","start":"2026-07-14T17:48:28.99292Z","end":"2026-07-14T17:48:29.428552Z","steps":["trace[602333125] 'process raft request' (duration: 435.391583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:29.428647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:28.992895Z","time spent":"435.711801ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:48:29.740798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.555291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T17:48:29.740885Z","caller":"traceutil/trace.go:171","msg":"trace[1025362496] linearizableReadLoop","detail":"{readStateIndex:1327; appliedIndex:1326; }","duration":"308.600564ms","start":"2026-07-14T17:48:29.432272Z","end":"2026-07-14T17:48:29.740872Z","steps":["trace[1025362496] 'read index received' (duration: 113.93372ms)","trace[1025362496] 'applied index is now lower than readState.Index' (duration: 194.665544ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:48:29.741059Z","caller":"traceutil/trace.go:171","msg":"trace[1681493682] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"309.233411ms","start":"2026-07-14T17:48:29.431815Z","end":"2026-07-14T17:48:29.741048Z","steps":["trace[1681493682] 'process raft request' (duration: 114.364922ms)","trace[1681493682] 'compare' (duration: 194.401338ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:48:29.741078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.790249ms","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-07-14T17:48:29.741107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:29.4318Z","time spent":"309.283182ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:48:29.741122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.057451ms","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-07-14T17:48:29.741153Z","caller":"traceutil/trace.go:171","msg":"trace[550081608] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1273; }","duration":"272.122112ms","start":"2026-07-14T17:48:29.469022Z","end":"2026-07-14T17:48:29.741144Z","steps":["trace[550081608] 'agreement among raft nodes before linearized reading' (duration: 272.03251ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:29.741148Z","caller":"traceutil/trace.go:171","msg":"trace[357007471] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1273; }","duration":"308.878111ms","start":"2026-07-14T17:48:29.432252Z","end":"2026-07-14T17:48:29.74113Z","steps":["trace[357007471] 'agreement among raft nodes before linearized reading' (duration: 308.689457ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:29.741197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:29.432244Z","time spent":"308.944913ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T17:48:29.741289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.314881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:48:29.741312Z","caller":"traceutil/trace.go:171","msg":"trace[890250487] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1273; }","duration":"244.364203ms","start":"2026-07-14T17:48:29.49694Z","end":"2026-07-14T17:48:29.741304Z","steps":["trace[890250487] 'agreement among raft nodes before linearized reading' (duration: 244.303501ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:29.741728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.152025ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T17:48:29.741773Z","caller":"traceutil/trace.go:171","msg":"trace[48610693] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1273; }","duration":"130.240347ms","start":"2026-07-14T17:48:29.611522Z","end":"2026-07-14T17:48:29.741763Z","steps":["trace[48610693] 'agreement among raft nodes before linearized reading' (duration: 130.107034ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:48:39.627415Z","caller":"traceutil/trace.go:171","msg":"trace[1334375158] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"180.443511ms","start":"2026-07-14T17:48:39.446945Z","end":"2026-07-14T17:48:39.627389Z","steps":["trace[1334375158] 'process raft request' (duration: 180.305918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.147647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.947939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T17:48:40.147721Z","caller":"traceutil/trace.go:171","msg":"trace[659251012] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1346; }","duration":"292.054001ms","start":"2026-07-14T17:48:39.855651Z","end":"2026-07-14T17:48:40.147705Z","steps":["trace[659251012] 'range keys from in-memory index tree' (duration: 291.789234ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.1477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.049225ms","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-07-14T17:48:40.14778Z","caller":"traceutil/trace.go:171","msg":"trace[1987969085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1346; }","duration":"289.179018ms","start":"2026-07-14T17:48:39.858583Z","end":"2026-07-14T17:48:40.147762Z","steps":["trace[1987969085] 'range keys from in-memory index tree' (duration: 288.88301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.148041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.356545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-14T17:48:40.148081Z","caller":"traceutil/trace.go:171","msg":"trace[1013116261] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1346; }","duration":"322.456708ms","start":"2026-07-14T17:48:39.825615Z","end":"2026-07-14T17:48:40.148072Z","steps":["trace[1013116261] 'range keys from in-memory index tree' (duration: 322.197431ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.148125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:39.82558Z","time spent":"322.536639ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-14T17:48:40.148271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.032092ms","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-07-14T17:48:40.148314Z","caller":"traceutil/trace.go:171","msg":"trace[1960269112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1346; }","duration":"294.089902ms","start":"2026-07-14T17:48:39.854212Z","end":"2026-07-14T17:48:40.148302Z","steps":["trace[1960269112] 'range keys from in-memory index tree' (duration: 293.94798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.149852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.071748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T17:48:40.150191Z","caller":"traceutil/trace.go:171","msg":"trace[239709269] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1346; }","duration":"371.469019ms","start":"2026-07-14T17:48:39.778704Z","end":"2026-07-14T17:48:40.150173Z","steps":["trace[239709269] 'range keys from in-memory index tree' (duration: 370.813173ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:48:40.150247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:48:39.778681Z","time spent":"371.547322ms","remote":"127.0.0.1:45690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:53:26.805597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.977252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wvkr6\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-07-14T17:53:26.805688Z","caller":"traceutil/trace.go:171","msg":"trace[1019934802] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wvkr6; range_end:; response_count:1; response_revision:2698; }","duration":"116.112396ms","start":"2026-07-14T17:53:26.689555Z","end":"2026-07-14T17:53:26.805667Z","steps":["trace[1019934802] 'range keys from in-memory index tree' (duration: 115.741387ms)"],"step_count":1}