{"level":"warn","ts":"2026-07-02T00:14:21.127947Z","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-02T00:14:21.128078Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4: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-02T00:14:21.128192Z","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-02T00:14:21.128216Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-02T00:14:21.128252Z","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-02T00:14:21.129375Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-07-02T00:14:21.129503Z","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.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4: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-02T00:14:21.134552Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.705654ms"} {"level":"info","ts":"2026-07-02T00:14:21.14237Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-07-02T00:14:21.142439Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-07-02T00:14:21.142458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-07-02T00:14:21.142471Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-02T00:14:21.142479Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-07-02T00:14:21.142514Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-07-02T00:14:21.149199Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-02T00:14:21.150643Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-02T00:14:21.15208Z","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-02T00:14:21.154956Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-02T00:14:21.155219Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-02T00:14:21.155289Z","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-02T00:14:21.155479Z","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-02T00:14:21.155502Z","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-02T00:14:21.157628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-07-02T00:14:21.157923Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-07-02T00:14:21.159802Z","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-02T00:14:21.159965Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-02T00:14:21.160287Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-07-02T00:14:21.160667Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-02T00:14:21.160761Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-02T00:14:21.742769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-07-02T00:14:21.742858Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-02T00:14:21.742882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-07-02T00:14:21.742946Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-07-02T00:14:21.742964Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-02T00:14:21.743065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-07-02T00:14:21.74309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-07-02T00:14:21.744197Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-07-02T00:14:21.744405Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:14:21.744468Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-02T00:14:21.744212Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:14:21.744709Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-02T00:14:21.744442Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-02T00:14:21.745837Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:14:21.745979Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:14:21.746065Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-02T00:14:21.748426Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-07-02T00:14:21.751495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-02T00:14:24.239868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.827179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-02T00:14:24.240074Z","caller":"traceutil/trace.go:171","msg":"trace[1751577938] linearizableReadLoop","detail":"{readStateIndex:78; appliedIndex:76; }","duration":"129.295414ms","start":"2026-07-02T00:14:24.11074Z","end":"2026-07-02T00:14:24.240036Z","steps":["trace[1751577938] 'read index received' (duration: 68.008509ms)","trace[1751577938] 'applied index is now lower than readState.Index' (duration: 61.285995ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:24.240144Z","caller":"traceutil/trace.go:171","msg":"trace[824683944] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"174.763108ms","start":"2026-07-02T00:14:24.065348Z","end":"2026-07-02T00:14:24.240111Z","steps":["trace[824683944] 'process raft request' (duration: 27.16229ms)","trace[824683944] 'compare' (duration: 146.681193ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:24.240507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.760172ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:14:24.240543Z","caller":"traceutil/trace.go:171","msg":"trace[1148158669] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:73; }","duration":"129.823174ms","start":"2026-07-02T00:14:24.110709Z","end":"2026-07-02T00:14:24.240532Z","steps":["trace[1148158669] 'agreement among raft nodes before linearized reading' (duration: 129.762752ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:24.435133Z","caller":"traceutil/trace.go:171","msg":"trace[1276924874] linearizableReadLoop","detail":"{readStateIndex:79; appliedIndex:78; }","duration":"191.912988ms","start":"2026-07-02T00:14:24.243188Z","end":"2026-07-02T00:14:24.435101Z","steps":["trace[1276924874] 'read index received' (duration: 171.546056ms)","trace[1276924874] 'applied index is now lower than readState.Index' (duration: 20.365742ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:24.435185Z","caller":"traceutil/trace.go:171","msg":"trace[1787557709] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"193.690696ms","start":"2026-07-02T00:14:24.241451Z","end":"2026-07-02T00:14:24.435142Z","steps":["trace[1787557709] 'process raft request' (duration: 173.276311ms)","trace[1787557709] 'compare' (duration: 20.194316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:24.435319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.105375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:discovery\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-02T00:14:24.435384Z","caller":"traceutil/trace.go:171","msg":"trace[278028023] range","detail":"{range_begin:/registry/clusterroles/system:discovery; range_end:; response_count:0; response_revision:74; }","duration":"192.19769ms","start":"2026-07-02T00:14:24.243153Z","end":"2026-07-02T00:14:24.435351Z","steps":["trace[278028023] 'agreement among raft nodes before linearized reading' (duration: 192.055814ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:24.640979Z","caller":"traceutil/trace.go:171","msg":"trace[1419909104] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"119.761362ms","start":"2026-07-02T00:14:24.521181Z","end":"2026-07-02T00:14:24.640942Z","steps":["trace[1419909104] 'process raft request' (duration: 37.69948ms)","trace[1419909104] 'compare' (duration: 81.875045ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:24.641134Z","caller":"traceutil/trace.go:171","msg":"trace[607153802] transaction","detail":"{read_only:false; response_revision:80; number_of_response:1; }","duration":"119.519463ms","start":"2026-07-02T00:14:24.521587Z","end":"2026-07-02T00:14:24.641107Z","steps":["trace[607153802] 'process raft request' (duration: 119.416379ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:25.211199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.203825ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:14:25.211502Z","caller":"traceutil/trace.go:171","msg":"trace[220112552] linearizableReadLoop","detail":"{readStateIndex:158; appliedIndex:157; }","duration":"100.210403ms","start":"2026-07-02T00:14:25.111248Z","end":"2026-07-02T00:14:25.211458Z","steps":["trace[220112552] 'read index received' (duration: 78.593µs)","trace[220112552] 'applied index is now lower than readState.Index' (duration: 100.12895ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:25.211839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.584046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:14:25.211891Z","caller":"traceutil/trace.go:171","msg":"trace[636425594] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:154; }","duration":"100.712492ms","start":"2026-07-02T00:14:25.11116Z","end":"2026-07-02T00:14:25.211873Z","steps":["trace[636425594] 'agreement among raft nodes before linearized reading' (duration: 100.494093ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:25.21214Z","caller":"traceutil/trace.go:171","msg":"trace[1592000982] transaction","detail":"{read_only:false; response_revision:153; number_of_response:1; }","duration":"149.632329ms","start":"2026-07-02T00:14:25.061976Z","end":"2026-07-02T00:14:25.211608Z","steps":["trace[1592000982] 'process raft request' (duration: 45.890243ms)","trace[1592000982] 'compare' (duration: 103.05669ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:25.841746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.744335ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:14:25.84196Z","caller":"traceutil/trace.go:171","msg":"trace[1837856736] transaction","detail":"{read_only:false; response_revision:157; number_of_response:1; }","duration":"565.383631ms","start":"2026-07-02T00:14:25.27652Z","end":"2026-07-02T00:14:25.841904Z","steps":["trace[1837856736] 'process raft request' (duration: 326.408757ms)","trace[1837856736] 'compare' (duration: 238.582169ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:25.8425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:25.2765Z","time spent":"565.63419ms","remote":"127.0.0.1:42630","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:14:26.090798Z","caller":"traceutil/trace.go:171","msg":"trace[344869602] transaction","detail":"{read_only:false; response_revision:165; number_of_response:1; }","duration":"148.763385ms","start":"2026-07-02T00:14:25.941813Z","end":"2026-07-02T00:14:26.090577Z","steps":["trace[344869602] 'process raft request' (duration: 69.046251ms)","trace[344869602] 'compare' (duration: 79.429993ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:26.117793Z","caller":"traceutil/trace.go:171","msg":"trace[2089107978] transaction","detail":"{read_only:false; response_revision:166; number_of_response:1; }","duration":"114.787669ms","start":"2026-07-02T00:14:26.002971Z","end":"2026-07-02T00:14:26.117759Z","steps":["trace[2089107978] 'process raft request' (duration: 114.596892ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:30.10471Z","caller":"traceutil/trace.go:171","msg":"trace[1728886293] transaction","detail":"{read_only:false; number_of_response:0; response_revision:307; }","duration":"163.006252ms","start":"2026-07-02T00:14:29.94168Z","end":"2026-07-02T00:14:30.104686Z","steps":["trace[1728886293] 'process raft request' (duration: 162.820315ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:30.104764Z","caller":"traceutil/trace.go:171","msg":"trace[111794972] linearizableReadLoop","detail":"{readStateIndex:321; appliedIndex:320; }","duration":"137.025583ms","start":"2026-07-02T00:14:29.967707Z","end":"2026-07-02T00:14:30.104732Z","steps":["trace[111794972] 'read index received' (duration: 41.687314ms)","trace[111794972] 'applied index is now lower than readState.Index' (duration: 95.336819ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:30.10484Z","caller":"traceutil/trace.go:171","msg":"trace[1120840918] transaction","detail":"{read_only:false; response_revision:308; number_of_response:1; }","duration":"119.896555ms","start":"2026-07-02T00:14:29.984913Z","end":"2026-07-02T00:14:30.10481Z","steps":["trace[1120840918] 'process raft request' (duration: 119.702978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:30.104918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.193849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/token-cleaner\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:14:30.104965Z","caller":"traceutil/trace.go:171","msg":"trace[1101062766] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/token-cleaner; range_end:; response_count:0; response_revision:308; }","duration":"137.289283ms","start":"2026-07-02T00:14:29.967666Z","end":"2026-07-02T00:14:30.104955Z","steps":["trace[1101062766] 'agreement among raft nodes before linearized reading' (duration: 137.20111ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:30.277329Z","caller":"traceutil/trace.go:171","msg":"trace[1745238545] linearizableReadLoop","detail":"{readStateIndex:324; appliedIndex:323; }","duration":"148.829232ms","start":"2026-07-02T00:14:30.128474Z","end":"2026-07-02T00:14:30.277304Z","steps":["trace[1745238545] 'read index received' (duration: 110.800742ms)","trace[1745238545] 'applied index is now lower than readState.Index' (duration: 38.02753ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:30.277433Z","caller":"traceutil/trace.go:171","msg":"trace[1805120039] transaction","detail":"{read_only:false; response_revision:310; number_of_response:1; }","duration":"161.894319ms","start":"2026-07-02T00:14:30.115506Z","end":"2026-07-02T00:14:30.2774Z","steps":["trace[1805120039] 'process raft request' (duration: 123.831798ms)","trace[1805120039] 'compare' (duration: 37.825032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:30.27747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.978707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/pv-protection-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:14:30.277507Z","caller":"traceutil/trace.go:171","msg":"trace[149578851] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/pv-protection-controller; range_end:; response_count:0; response_revision:310; }","duration":"149.034859ms","start":"2026-07-02T00:14:30.128459Z","end":"2026-07-02T00:14:30.277494Z","steps":["trace[149578851] 'agreement among raft nodes before linearized reading' (duration: 148.939106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:30.957808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.819513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-02T00:14:30.957902Z","caller":"traceutil/trace.go:171","msg":"trace[1491060942] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:317; }","duration":"157.952168ms","start":"2026-07-02T00:14:30.799923Z","end":"2026-07-02T00:14:30.957875Z","steps":["trace[1491060942] 'range keys from in-memory index tree' (duration: 157.683128ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:31.224601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.164523ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:14:31.224681Z","caller":"traceutil/trace.go:171","msg":"trace[602040564] linearizableReadLoop","detail":"{readStateIndex:333; appliedIndex:332; }","duration":"203.55482ms","start":"2026-07-02T00:14:31.021115Z","end":"2026-07-02T00:14:31.22467Z","steps":["trace[602040564] 'read index received' (duration: 41.071765ms)","trace[602040564] 'applied index is now lower than readState.Index' (duration: 162.482205ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:31.224721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.620981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:14:31.224736Z","caller":"traceutil/trace.go:171","msg":"trace[255175074] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:0; response_revision:319; }","duration":"203.677874ms","start":"2026-07-02T00:14:31.021052Z","end":"2026-07-02T00:14:31.22473Z","steps":["trace[255175074] 'agreement among raft nodes before linearized reading' (duration: 203.647303ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:31.224754Z","caller":"traceutil/trace.go:171","msg":"trace[1541072789] transaction","detail":"{read_only:false; response_revision:319; number_of_response:1; }","duration":"261.840278ms","start":"2026-07-02T00:14:30.962873Z","end":"2026-07-02T00:14:31.224714Z","steps":["trace[1541072789] 'process raft request' (duration: 99.398585ms)","trace[1541072789] 'compare' (duration: 162.041189ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:32.148834Z","caller":"traceutil/trace.go:171","msg":"trace[1817385145] linearizableReadLoop","detail":"{readStateIndex:338; appliedIndex:337; }","duration":"202.40355ms","start":"2026-07-02T00:14:31.946404Z","end":"2026-07-02T00:14:32.148808Z","steps":["trace[1817385145] 'read index received' (duration: 202.250115ms)","trace[1817385145] 'applied index is now lower than readState.Index' (duration: 152.455µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:32.148939Z","caller":"traceutil/trace.go:171","msg":"trace[872420456] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"648.131037ms","start":"2026-07-02T00:14:31.500775Z","end":"2026-07-02T00:14:32.148907Z","steps":["trace[872420456] 'process raft request' (duration: 647.903229ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:32.149028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.718662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:14:32.149033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.618539ms","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-02T00:14:32.14907Z","caller":"traceutil/trace.go:171","msg":"trace[164997543] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:324; }","duration":"167.803095ms","start":"2026-07-02T00:14:31.981257Z","end":"2026-07-02T00:14:32.14906Z","steps":["trace[164997543] 'agreement among raft nodes before linearized reading' (duration: 167.710522ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:32.149077Z","caller":"traceutil/trace.go:171","msg":"trace[1633519261] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:324; }","duration":"202.713871ms","start":"2026-07-02T00:14:31.946351Z","end":"2026-07-02T00:14:32.149065Z","steps":["trace[1633519261] 'agreement among raft nodes before linearized reading' (duration: 202.606808ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:32.149228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:31.500746Z","time spent":"648.385416ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:14:32.785405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.124547ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:14:32.785497Z","caller":"traceutil/trace.go:171","msg":"trace[754443769] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:338; }","duration":"631.951889ms","start":"2026-07-02T00:14:32.153529Z","end":"2026-07-02T00:14:32.785481Z","steps":["trace[754443769] 'read index received' (duration: 345.600064ms)","trace[754443769] 'applied index is now lower than readState.Index' (duration: 286.350385ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:32.785603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.068383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:14:32.785956Z","caller":"traceutil/trace.go:171","msg":"trace[803768723] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:0; response_revision:325; }","duration":"632.409735ms","start":"2026-07-02T00:14:32.153484Z","end":"2026-07-02T00:14:32.785894Z","steps":["trace[803768723] 'agreement among raft nodes before linearized reading' (duration: 632.041462ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:32.786044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.153462Z","time spent":"632.56927ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-07-02T00:14:32.785582Z","caller":"traceutil/trace.go:171","msg":"trace[854247633] transaction","detail":"{read_only:false; response_revision:325; number_of_response:1; }","duration":"632.454356ms","start":"2026-07-02T00:14:32.153095Z","end":"2026-07-02T00:14:32.785549Z","steps":["trace[854247633] 'process raft request' (duration: 346.000428ms)","trace[854247633] 'compare' (duration: 285.482084ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:32.786266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.15307Z","time spent":"633.023116ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:14:33.153083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.927185ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:14:33.153262Z","caller":"traceutil/trace.go:171","msg":"trace[65324899] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"362.827199ms","start":"2026-07-02T00:14:32.790417Z","end":"2026-07-02T00:14:33.153244Z","steps":["trace[65324899] 'process raft request' (duration: 362.767077ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:33.153323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.790399Z","time spent":"362.899362ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:14:33.153528Z","caller":"traceutil/trace.go:171","msg":"trace[1088239712] linearizableReadLoop","detail":"{readStateIndex:340; appliedIndex:339; }","duration":"364.349312ms","start":"2026-07-02T00:14:32.789168Z","end":"2026-07-02T00:14:33.153517Z","steps":["trace[1088239712] 'read index received' (duration: 143.873287ms)","trace[1088239712] 'applied index is now lower than readState.Index' (duration: 220.474945ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:33.153661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.497757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-02T00:14:33.153696Z","caller":"traceutil/trace.go:171","msg":"trace[167028320] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:327; }","duration":"364.57038ms","start":"2026-07-02T00:14:32.789116Z","end":"2026-07-02T00:14:33.153686Z","steps":["trace[167028320] 'agreement among raft nodes before linearized reading' (duration: 364.482427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:33.153707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.099103ms","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-07-02T00:14:33.153726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.789094Z","time spent":"364.624961ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-02T00:14:33.153741Z","caller":"traceutil/trace.go:171","msg":"trace[725273915] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:327; }","duration":"364.180066ms","start":"2026-07-02T00:14:32.789554Z","end":"2026-07-02T00:14:33.153734Z","steps":["trace[725273915] 'agreement among raft nodes before linearized reading' (duration: 364.096973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:33.153767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.789536Z","time spent":"364.225858ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-02T00:14:33.15348Z","caller":"traceutil/trace.go:171","msg":"trace[563596341] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"999.561005ms","start":"2026-07-02T00:14:32.15389Z","end":"2026-07-02T00:14:33.153451Z","steps":["trace[563596341] 'process raft request' (duration: 779.194914ms)","trace[563596341] 'compare' (duration: 219.831032ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:33.154173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:14:32.153875Z","time spent":"1.000076323s","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:14:41.355429Z","caller":"traceutil/trace.go:171","msg":"trace[1640856908] transaction","detail":"{read_only:false; response_revision:416; number_of_response:1; }","duration":"160.766359ms","start":"2026-07-02T00:14:41.194631Z","end":"2026-07-02T00:14:41.355398Z","steps":["trace[1640856908] 'process raft request' (duration: 160.568793ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:41.408785Z","caller":"traceutil/trace.go:171","msg":"trace[2137048431] transaction","detail":"{read_only:false; response_revision:417; number_of_response:1; }","duration":"212.546211ms","start":"2026-07-02T00:14:41.196218Z","end":"2026-07-02T00:14:41.408765Z","steps":["trace[2137048431] 'process raft request' (duration: 212.332644ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:41.408867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.155427ms","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-02T00:14:41.408928Z","caller":"traceutil/trace.go:171","msg":"trace[764419239] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:417; }","duration":"137.26909ms","start":"2026-07-02T00:14:41.271643Z","end":"2026-07-02T00:14:41.408912Z","steps":["trace[764419239] 'agreement among raft nodes before linearized reading' (duration: 137.157897ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:41.409366Z","caller":"traceutil/trace.go:171","msg":"trace[1000036491] linearizableReadLoop","detail":"{readStateIndex:433; appliedIndex:431; }","duration":"136.989751ms","start":"2026-07-02T00:14:41.271701Z","end":"2026-07-02T00:14:41.408691Z","steps":["trace[1000036491] 'read index received' (duration: 83.586807ms)","trace[1000036491] 'applied index is now lower than readState.Index' (duration: 53.401734ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:48.329587Z","caller":"traceutil/trace.go:171","msg":"trace[1708574982] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"106.453891ms","start":"2026-07-02T00:14:48.223104Z","end":"2026-07-02T00:14:48.329557Z","steps":["trace[1708574982] 'process raft request' (duration: 106.308047ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:48.468354Z","caller":"traceutil/trace.go:171","msg":"trace[359111102] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"118.991712ms","start":"2026-07-02T00:14:48.349334Z","end":"2026-07-02T00:14:48.468326Z","steps":["trace[359111102] 'process raft request' (duration: 118.911049ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:48.468504Z","caller":"traceutil/trace.go:171","msg":"trace[1436595011] transaction","detail":"{read_only:false; response_revision:438; number_of_response:1; }","duration":"121.953273ms","start":"2026-07-02T00:14:48.346531Z","end":"2026-07-02T00:14:48.468485Z","steps":["trace[1436595011] 'process raft request' (duration: 58.168116ms)","trace[1436595011] 'compare' (duration: 63.398745ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:48.696569Z","caller":"traceutil/trace.go:171","msg":"trace[1037241798] linearizableReadLoop","detail":"{readStateIndex:464; appliedIndex:463; }","duration":"175.032633ms","start":"2026-07-02T00:14:48.521507Z","end":"2026-07-02T00:14:48.69654Z","steps":["trace[1037241798] 'read index received' (duration: 129.431959ms)","trace[1037241798] 'applied index is now lower than readState.Index' (duration: 45.599504ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:48.69725Z","caller":"traceutil/trace.go:171","msg":"trace[1906561392] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"200.434754ms","start":"2026-07-02T00:14:48.496789Z","end":"2026-07-02T00:14:48.697224Z","steps":["trace[1906561392] 'process raft request' (duration: 154.246172ms)","trace[1906561392] 'compare' (duration: 45.210282ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:48.697268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.150756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-07-02T00:14:48.697331Z","caller":"traceutil/trace.go:171","msg":"trace[1677771402] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:446; }","duration":"199.236908ms","start":"2026-07-02T00:14:48.498081Z","end":"2026-07-02T00:14:48.697317Z","steps":["trace[1677771402] 'agreement among raft nodes before linearized reading' (duration: 199.106634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:14:48.697332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.347743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-07-02T00:14:48.697388Z","caller":"traceutil/trace.go:171","msg":"trace[244279454] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:446; }","duration":"173.449606ms","start":"2026-07-02T00:14:48.523922Z","end":"2026-07-02T00:14:48.697371Z","steps":["trace[244279454] 'agreement among raft nodes before linearized reading' (duration: 173.319642ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:53.646419Z","caller":"traceutil/trace.go:171","msg":"trace[710738083] linearizableReadLoop","detail":"{readStateIndex:496; appliedIndex:495; }","duration":"167.159776ms","start":"2026-07-02T00:14:53.479232Z","end":"2026-07-02T00:14:53.646391Z","steps":["trace[710738083] 'read index received' (duration: 126.101361ms)","trace[710738083] 'applied index is now lower than readState.Index' (duration: 41.057525ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:14:53.646491Z","caller":"traceutil/trace.go:171","msg":"trace[833499161] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"169.003609ms","start":"2026-07-02T00:14:53.477463Z","end":"2026-07-02T00:14:53.646466Z","steps":["trace[833499161] 'process raft request' (duration: 127.767379ms)","trace[833499161] 'compare' (duration: 41.047955ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:14:53.646547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.28679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:14:53.646584Z","caller":"traceutil/trace.go:171","msg":"trace[360005098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"167.357402ms","start":"2026-07-02T00:14:53.479214Z","end":"2026-07-02T00:14:53.646572Z","steps":["trace[360005098] 'agreement among raft nodes before linearized reading' (duration: 167.273439ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:14:53.683511Z","caller":"traceutil/trace.go:171","msg":"trace[131774025] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"204.172323ms","start":"2026-07-02T00:14:53.479312Z","end":"2026-07-02T00:14:53.683484Z","steps":["trace[131774025] 'process raft request' (duration: 204.034529ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.01518Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312419896777364,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-02T00:15:02.080103Z","caller":"traceutil/trace.go:171","msg":"trace[1727538393] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"734.724936ms","start":"2026-07-02T00:15:01.345348Z","end":"2026-07-02T00:15:02.080073Z","steps":["trace[1727538393] 'process raft request' (duration: 734.556651ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.080319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.345329Z","time spent":"734.922322ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:02.762518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.850078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:02.762905Z","caller":"traceutil/trace.go:171","msg":"trace[569554346] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.417300791s","start":"2026-07-02T00:15:01.345567Z","end":"2026-07-02T00:15:02.762867Z","steps":["trace[569554346] 'process raft request' (duration: 1.235020752s)","trace[569554346] 'compare' (duration: 181.303182ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:02.762944Z","caller":"traceutil/trace.go:171","msg":"trace[71801280] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"1.40702331s","start":"2026-07-02T00:15:01.35589Z","end":"2026-07-02T00:15:02.762914Z","steps":["trace[71801280] 'process raft request' (duration: 1.406873226s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.762993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.345559Z","time spent":"1.417393613s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763009Z","caller":"traceutil/trace.go:171","msg":"trace[720084141] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.407735669s","start":"2026-07-02T00:15:01.355265Z","end":"2026-07-02T00:15:02.763Z","steps":["trace[720084141] 'process raft request' (duration: 1.407413021s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:02.762963Z","caller":"traceutil/trace.go:171","msg":"trace[1169895209] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.417046504s","start":"2026-07-02T00:15:01.345793Z","end":"2026-07-02T00:15:02.762839Z","steps":["trace[1169895209] 'process raft request' (duration: 1.416817028s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.355242Z","time spent":"1.407799601s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763073Z","caller":"traceutil/trace.go:171","msg":"trace[21736401] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"1.3949393s","start":"2026-07-02T00:15:01.368082Z","end":"2026-07-02T00:15:02.763022Z","steps":["trace[21736401] 'process raft request' (duration: 1.394754505s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.355884Z","time spent":"1.407132854s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763171Z","caller":"traceutil/trace.go:171","msg":"trace[545188510] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.383313593s","start":"2026-07-02T00:15:01.379851Z","end":"2026-07-02T00:15:02.763164Z","steps":["trace[545188510] 'process raft request' (duration: 1.383029785s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.379834Z","time spent":"1.383361494s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.762944Z","caller":"traceutil/trace.go:171","msg":"trace[1854567151] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"1.407329659s","start":"2026-07-02T00:15:01.355568Z","end":"2026-07-02T00:15:02.762898Z","steps":["trace[1854567151] 'process raft request' (duration: 1.407161414s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.345786Z","time spent":"1.417315221s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:02.76325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.368076Z","time spent":"1.395096424s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763261Z","caller":"traceutil/trace.go:171","msg":"trace[714044041] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"1.373084213s","start":"2026-07-02T00:15:01.390165Z","end":"2026-07-02T00:15:02.763249Z","steps":["trace[714044041] 'process raft request' (duration: 1.372761294s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.355561Z","time spent":"1.407683068s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:02.763324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.390148Z","time spent":"1.373152295s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.76312Z","caller":"traceutil/trace.go:171","msg":"trace[1822945901] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"1.395096544s","start":"2026-07-02T00:15:01.368013Z","end":"2026-07-02T00:15:02.76311Z","steps":["trace[1822945901] 'process raft request' (duration: 1.394787846s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.36799Z","time spent":"1.395394103s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763478Z","caller":"traceutil/trace.go:171","msg":"trace[1465102829] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"1.239133286s","start":"2026-07-02T00:15:01.524334Z","end":"2026-07-02T00:15:02.763468Z","steps":["trace[1465102829] 'process raft request' (duration: 1.238881709s)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:02.763473Z","caller":"traceutil/trace.go:171","msg":"trace[1398481712] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:549; }","duration":"1.249042286s","start":"2026-07-02T00:15:01.514417Z","end":"2026-07-02T00:15:02.763459Z","steps":["trace[1398481712] 'read index received' (duration: 565.587747ms)","trace[1398481712] 'applied index is now lower than readState.Index' (duration: 683.453509ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:02.763483Z","caller":"traceutil/trace.go:171","msg":"trace[710404024] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"1.354953899s","start":"2026-07-02T00:15:01.408504Z","end":"2026-07-02T00:15:02.763457Z","steps":["trace[710404024] 'process raft request' (duration: 1.354473536s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.524281Z","time spent":"1.239217578s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:02.763633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.249203411s","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-02T00:15:02.763638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.408469Z","time spent":"1.355075802s","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:02.763674Z","caller":"traceutil/trace.go:171","msg":"trace[2046679701] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:539; }","duration":"1.249271612s","start":"2026-07-02T00:15:01.514393Z","end":"2026-07-02T00:15:02.763664Z","steps":["trace[2046679701] 'agreement among raft nodes before linearized reading' (duration: 1.249135839s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:02.763712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.514383Z","time spent":"1.249321883s","remote":"127.0.0.1:42530","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":"info","ts":"2026-07-02T00:15:03.05748Z","caller":"traceutil/trace.go:171","msg":"trace[1909096246] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"971.163503ms","start":"2026-07-02T00:15:02.086245Z","end":"2026-07-02T00:15:03.057408Z","steps":["trace[1909096246] 'process raft request' (duration: 948.75202ms)","trace[1909096246] 'compare' (duration: 22.052663ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:03.057715Z","caller":"traceutil/trace.go:171","msg":"trace[1530117646] transaction","detail":"{read_only:false; number_of_response:1; response_revision:541; }","duration":"969.793136ms","start":"2026-07-02T00:15:02.087882Z","end":"2026-07-02T00:15:03.057675Z","steps":["trace[1530117646] 'process raft request' (duration: 969.515379ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.057589Z","caller":"traceutil/trace.go:171","msg":"trace[1177727953] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"970.481805ms","start":"2026-07-02T00:15:02.087066Z","end":"2026-07-02T00:15:03.057548Z","steps":["trace[1177727953] 'process raft request' (duration: 970.252859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.057905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:02.087874Z","time spent":"969.92108ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7366,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:03.057876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:02.086224Z","time spent":"971.458802ms","remote":"127.0.0.1:42802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:03.058056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:02.087057Z","time spent":"970.880265ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:03.067716Z","caller":"traceutil/trace.go:171","msg":"trace[803125052] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:561; }","duration":"304.183739ms","start":"2026-07-02T00:15:02.763506Z","end":"2026-07-02T00:15:03.06769Z","steps":["trace[803125052] 'read index received' (duration: 271.542841ms)","trace[803125052] 'applied index is now lower than readState.Index' (duration: 32.639358ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:03.067861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.390554383s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:15:03.067917Z","caller":"traceutil/trace.go:171","msg":"trace[655550884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:541; }","duration":"1.390657506s","start":"2026-07-02T00:15:01.677244Z","end":"2026-07-02T00:15:03.067902Z","steps":["trace[655550884] 'agreement among raft nodes before linearized reading' (duration: 1.390538033s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.677221Z","time spent":"1.390767209s","remote":"127.0.0.1:42530","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-02T00:15:03.068392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.34945121s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:15:03.068462Z","caller":"traceutil/trace.go:171","msg":"trace[1389603893] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"1.349519173s","start":"2026-07-02T00:15:01.718912Z","end":"2026-07-02T00:15:03.068431Z","steps":["trace[1389603893] 'agreement among raft nodes before linearized reading' (duration: 1.34943518s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.068504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.718905Z","time spent":"1.349588674s","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-02T00:15:03.068697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.351813954s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:03.068735Z","caller":"traceutil/trace.go:171","msg":"trace[1486115006] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:541; }","duration":"1.351900427s","start":"2026-07-02T00:15:01.716825Z","end":"2026-07-02T00:15:03.068725Z","steps":["trace[1486115006] 'agreement among raft nodes before linearized reading' (duration: 1.351844196s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.068767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.716806Z","time spent":"1.351953988s","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:15:03.068944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.356988746s","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-02T00:15:03.068981Z","caller":"traceutil/trace.go:171","msg":"trace[684717144] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:541; }","duration":"1.357064928s","start":"2026-07-02T00:15:01.711907Z","end":"2026-07-02T00:15:03.068971Z","steps":["trace[684717144] 'agreement among raft nodes before linearized reading' (duration: 1.357005196s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.069009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:01.711886Z","time spent":"1.357117359s","remote":"127.0.0.1:42530","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-02T00:15:03.06981Z","caller":"traceutil/trace.go:171","msg":"trace[305623404] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"299.376108ms","start":"2026-07-02T00:15:02.770406Z","end":"2026-07-02T00:15:03.069782Z","steps":["trace[305623404] 'process raft request' (duration: 299.08542ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.070107Z","caller":"traceutil/trace.go:171","msg":"trace[1674888140] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"295.067431ms","start":"2026-07-02T00:15:02.775031Z","end":"2026-07-02T00:15:03.070099Z","steps":["trace[1674888140] 'process raft request' (duration: 294.729672ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.07023Z","caller":"traceutil/trace.go:171","msg":"trace[527676622] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"299.598964ms","start":"2026-07-02T00:15:02.770623Z","end":"2026-07-02T00:15:03.070222Z","steps":["trace[527676622] 'process raft request' (duration: 299.09024ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.070497Z","caller":"traceutil/trace.go:171","msg":"trace[952986059] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"292.261985ms","start":"2026-07-02T00:15:02.778227Z","end":"2026-07-02T00:15:03.070489Z","steps":["trace[952986059] 'process raft request' (duration: 291.786972ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.070717Z","caller":"traceutil/trace.go:171","msg":"trace[340235212] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"293.132419ms","start":"2026-07-02T00:15:02.777577Z","end":"2026-07-02T00:15:03.07071Z","steps":["trace[340235212] 'process raft request' (duration: 292.220774ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.071268Z","caller":"traceutil/trace.go:171","msg":"trace[680630253] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"288.79729ms","start":"2026-07-02T00:15:02.782463Z","end":"2026-07-02T00:15:03.07126Z","steps":["trace[680630253] 'process raft request' (duration: 287.604248ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.071446Z","caller":"traceutil/trace.go:171","msg":"trace[229551601] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"284.188735ms","start":"2026-07-02T00:15:02.787252Z","end":"2026-07-02T00:15:03.07144Z","steps":["trace[229551601] 'process raft request' (duration: 283.196409ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.071485Z","caller":"traceutil/trace.go:171","msg":"trace[1338781636] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"288.295077ms","start":"2026-07-02T00:15:02.783164Z","end":"2026-07-02T00:15:03.071459Z","steps":["trace[1338781636] 'process raft request' (duration: 287.236788ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.071676Z","caller":"traceutil/trace.go:171","msg":"trace[2099571499] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"245.96312ms","start":"2026-07-02T00:15:02.825692Z","end":"2026-07-02T00:15:03.071655Z","steps":["trace[2099571499] 'process raft request' (duration: 245.757234ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:03.071717Z","caller":"traceutil/trace.go:171","msg":"trace[1414448947] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"283.884617ms","start":"2026-07-02T00:15:02.787824Z","end":"2026-07-02T00:15:03.071709Z","steps":["trace[1414448947] 'process raft request' (duration: 282.677015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.580698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.226584ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:03.580784Z","caller":"traceutil/trace.go:171","msg":"trace[2052579199] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"436.047244ms","start":"2026-07-02T00:15:03.144723Z","end":"2026-07-02T00:15:03.58077Z","steps":["trace[2052579199] 'process raft request' (duration: 248.682986ms)","trace[2052579199] 'compare' (duration: 187.116751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:03.580824Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.144707Z","time spent":"436.101245ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:03.613404Z","caller":"traceutil/trace.go:171","msg":"trace[1999067909] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"449.684511ms","start":"2026-07-02T00:15:03.163696Z","end":"2026-07-02T00:15:03.61338Z","steps":["trace[1999067909] 'process raft request' (duration: 449.434994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.613569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.163674Z","time spent":"449.798554ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10855,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:03.614202Z","caller":"traceutil/trace.go:171","msg":"trace[975490024] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"449.437805ms","start":"2026-07-02T00:15:03.164751Z","end":"2026-07-02T00:15:03.614189Z","steps":["trace[975490024] 'process raft request' (duration: 449.225649ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:03.614259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.164743Z","time spent":"449.487026ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":97665,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:04.316366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.014407ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:04.316496Z","caller":"traceutil/trace.go:171","msg":"trace[196232301] linearizableReadLoop","detail":"{readStateIndex:596; appliedIndex:595; }","duration":"702.415374ms","start":"2026-07-02T00:15:03.61406Z","end":"2026-07-02T00:15:04.316475Z","steps":["trace[196232301] 'read index received' (duration: 393.317915ms)","trace[196232301] 'applied index is now lower than readState.Index' (duration: 309.095878ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:04.316559Z","caller":"traceutil/trace.go:171","msg":"trace[837443369] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"721.115826ms","start":"2026-07-02T00:15:03.595418Z","end":"2026-07-02T00:15:04.316533Z","steps":["trace[837443369] 'process raft request' (duration: 411.829382ms)","trace[837443369] 'compare' (duration: 308.866043ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:04.316594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.034312ms","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-02T00:15:04.31664Z","caller":"traceutil/trace.go:171","msg":"trace[409050778] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:568; }","duration":"760.117095ms","start":"2026-07-02T00:15:03.556512Z","end":"2026-07-02T00:15:04.316629Z","steps":["trace[409050778] 'agreement among raft nodes before linearized reading' (duration: 760.037383ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.316668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.595397Z","time spent":"721.210039ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:04.316679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.556507Z","time spent":"760.162296ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-07-02T00:15:04.316837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"760.781193ms","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-02T00:15:04.316861Z","caller":"traceutil/trace.go:171","msg":"trace[840017115] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:568; }","duration":"760.805564ms","start":"2026-07-02T00:15:03.556047Z","end":"2026-07-02T00:15:04.316853Z","steps":["trace[840017115] 'agreement among raft nodes before linearized reading' (duration: 760.761452ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.31688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.556031Z","time spent":"760.843754ms","remote":"127.0.0.1:46242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-02T00:15:04.375127Z","caller":"traceutil/trace.go:171","msg":"trace[492033918] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"708.364192ms","start":"2026-07-02T00:15:03.666735Z","end":"2026-07-02T00:15:04.375099Z","steps":["trace[492033918] 'process raft request' (duration: 708.30892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.375258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.666694Z","time spent":"708.511075ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-02T00:15:04.375439Z","caller":"traceutil/trace.go:171","msg":"trace[1331468858] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"752.240991ms","start":"2026-07-02T00:15:03.623164Z","end":"2026-07-02T00:15:04.375405Z","steps":["trace[1331468858] 'process raft request' (duration: 751.720657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.375664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.623125Z","time spent":"752.404275ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:04.768194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.208363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:04.768506Z","caller":"traceutil/trace.go:171","msg":"trace[317015460] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"378.83587ms","start":"2026-07-02T00:15:04.389657Z","end":"2026-07-02T00:15:04.768492Z","steps":["trace[317015460] 'process raft request' (duration: 378.713637ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.768552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.389631Z","time spent":"378.901081ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:04.768649Z","caller":"traceutil/trace.go:171","msg":"trace[627500911] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"439.955871ms","start":"2026-07-02T00:15:04.328686Z","end":"2026-07-02T00:15:04.768642Z","steps":["trace[627500911] 'process raft request' (duration: 439.602231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.768682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.328642Z","time spent":"440.023282ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:04.768651Z","caller":"traceutil/trace.go:171","msg":"trace[17779154] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"332.460182ms","start":"2026-07-02T00:15:04.436144Z","end":"2026-07-02T00:15:04.768604Z","steps":["trace[17779154] 'process raft request' (duration: 332.314348ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:04.768742Z","caller":"traceutil/trace.go:171","msg":"trace[159570679] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"452.215397ms","start":"2026-07-02T00:15:04.316521Z","end":"2026-07-02T00:15:04.768736Z","steps":["trace[159570679] 'read index received' (duration: 58.970673ms)","trace[159570679] 'applied index is now lower than readState.Index' (duration: 393.243774ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:04.768796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.436113Z","time spent":"332.607736ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":73770,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:04.768868Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.314147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.768891Z","caller":"traceutil/trace.go:171","msg":"trace[1036626198] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:574; }","duration":"844.344468ms","start":"2026-07-02T00:15:03.924539Z","end":"2026-07-02T00:15:04.768883Z","steps":["trace[1036626198] 'agreement among raft nodes before linearized reading' (duration: 844.283917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.768908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.924534Z","time spent":"844.370379ms","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 "} {"level":"warn","ts":"2026-07-02T00:15:04.769029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.525223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769049Z","caller":"traceutil/trace.go:171","msg":"trace[780590767] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:574; }","duration":"844.557184ms","start":"2026-07-02T00:15:03.924485Z","end":"2026-07-02T00:15:04.769043Z","steps":["trace[780590767] 'agreement among raft nodes before linearized reading' (duration: 844.528823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.769064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.92448Z","time spent":"844.579344ms","remote":"127.0.0.1:46264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:15:04.769147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"844.693907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769165Z","caller":"traceutil/trace.go:171","msg":"trace[385418343] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:574; }","duration":"844.732999ms","start":"2026-07-02T00:15:03.924428Z","end":"2026-07-02T00:15:04.769161Z","steps":["trace[385418343] 'agreement among raft nodes before linearized reading' (duration: 844.705718ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.769182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.924417Z","time spent":"844.757719ms","remote":"127.0.0.1:46264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-02T00:15:04.769238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.748749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769255Z","caller":"traceutil/trace.go:171","msg":"trace[1387518221] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:574; }","duration":"860.80796ms","start":"2026-07-02T00:15:03.908443Z","end":"2026-07-02T00:15:04.769251Z","steps":["trace[1387518221] 'agreement among raft nodes before linearized reading' (duration: 860.783799ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.769271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.908417Z","time spent":"860.850432ms","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-07-02T00:15:04.769326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"860.979865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769343Z","caller":"traceutil/trace.go:171","msg":"trace[2067279548] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:574; }","duration":"861.012725ms","start":"2026-07-02T00:15:03.908326Z","end":"2026-07-02T00:15:04.769339Z","steps":["trace[2067279548] 'agreement among raft nodes before linearized reading' (duration: 860.987505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.769361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.908319Z","time spent":"861.036437ms","remote":"127.0.0.1:46250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:15:04.769418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"884.62681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769435Z","caller":"traceutil/trace.go:171","msg":"trace[1484556552] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:574; }","duration":"884.644471ms","start":"2026-07-02T00:15:03.884786Z","end":"2026-07-02T00:15:04.769431Z","steps":["trace[1484556552] 'agreement among raft nodes before linearized reading' (duration: 884.61946ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.769448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.884761Z","time spent":"884.683382ms","remote":"127.0.0.1:46246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 "} {"level":"info","ts":"2026-07-02T00:15:04.769059Z","caller":"traceutil/trace.go:171","msg":"trace[785031624] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"575.118397ms","start":"2026-07-02T00:15:04.193867Z","end":"2026-07-02T00:15:04.768985Z","steps":["trace[785031624] 'process raft request' (duration: 368.044111ms)","trace[785031624] 'compare' (duration: 205.947996ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:04.769542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.193833Z","time spent":"575.690612ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:04.769586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.259598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769636Z","caller":"traceutil/trace.go:171","msg":"trace[1338789139] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:574; }","duration":"885.32875ms","start":"2026-07-02T00:15:03.884297Z","end":"2026-07-02T00:15:04.769625Z","steps":["trace[1338789139] 'agreement among raft nodes before linearized reading' (duration: 885.246518ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.76966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.88429Z","time spent":"885.364931ms","remote":"127.0.0.1:46246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-07-02T00:15:04.769777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"885.622917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:04.769801Z","caller":"traceutil/trace.go:171","msg":"trace[1499720990] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:574; }","duration":"885.683549ms","start":"2026-07-02T00:15:03.884111Z","end":"2026-07-02T00:15:04.769794Z","steps":["trace[1499720990] 'agreement among raft nodes before linearized reading' (duration: 885.647258ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:04.76982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:03.884082Z","time spent":"885.73253ms","remote":"127.0.0.1:46246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 "} {"level":"info","ts":"2026-07-02T00:15:05.197808Z","caller":"traceutil/trace.go:171","msg":"trace[826678106] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"411.698258ms","start":"2026-07-02T00:15:04.786079Z","end":"2026-07-02T00:15:05.197777Z","steps":["trace[826678106] 'process raft request' (duration: 347.954129ms)","trace[826678106] 'compare' (duration: 63.440401ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:05.197983Z","caller":"traceutil/trace.go:171","msg":"trace[1036146220] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"411.860112ms","start":"2026-07-02T00:15:04.786086Z","end":"2026-07-02T00:15:05.197946Z","steps":["trace[1036146220] 'process raft request' (duration: 411.610876ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:05.198011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.786052Z","time spent":"411.849642ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:05.198163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.786066Z","time spent":"411.992675ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:05.200687Z","caller":"traceutil/trace.go:171","msg":"trace[1117590992] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:602; }","duration":"122.859923ms","start":"2026-07-02T00:15:05.077805Z","end":"2026-07-02T00:15:05.200665Z","steps":["trace[1117590992] 'read index received' (duration: 56.116156ms)","trace[1117590992] 'applied index is now lower than readState.Index' (duration: 66.742347ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:05.200816Z","caller":"traceutil/trace.go:171","msg":"trace[1844128657] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"402.119602ms","start":"2026-07-02T00:15:04.798588Z","end":"2026-07-02T00:15:05.200707Z","steps":["trace[1844128657] 'process raft request' (duration: 401.950967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:05.200923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:04.798561Z","time spent":"402.320497ms","remote":"127.0.0.1:42360","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19834,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:05.20095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.133151ms","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-02T00:15:05.201009Z","caller":"traceutil/trace.go:171","msg":"trace[1984887374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"123.233934ms","start":"2026-07-02T00:15:05.077758Z","end":"2026-07-02T00:15:05.200992Z","steps":["trace[1984887374] 'agreement among raft nodes before linearized reading' (duration: 123.013398ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:05.710026Z","caller":"traceutil/trace.go:171","msg":"trace[350522413] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"128.975486ms","start":"2026-07-02T00:15:05.581021Z","end":"2026-07-02T00:15:05.709996Z","steps":["trace[350522413] 'process raft request' (duration: 107.159099ms)","trace[350522413] 'compare' (duration: 21.569401ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:05.710405Z","caller":"traceutil/trace.go:171","msg":"trace[1290166875] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"116.528647ms","start":"2026-07-02T00:15:05.59386Z","end":"2026-07-02T00:15:05.710389Z","steps":["trace[1290166875] 'process raft request' (duration: 116.467365ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:05.710517Z","caller":"traceutil/trace.go:171","msg":"trace[479012597] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"121.928069ms","start":"2026-07-02T00:15:05.588561Z","end":"2026-07-02T00:15:05.710489Z","steps":["trace[479012597] 'process raft request' (duration: 121.673103ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:06.402254Z","caller":"traceutil/trace.go:171","msg":"trace[644511237] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"176.50232ms","start":"2026-07-02T00:15:06.225721Z","end":"2026-07-02T00:15:06.402223Z","steps":["trace[644511237] 'read index received' (duration: 175.961935ms)","trace[644511237] 'applied index is now lower than readState.Index' (duration: 539.325µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:06.402353Z","caller":"traceutil/trace.go:171","msg":"trace[1199317588] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"179.964721ms","start":"2026-07-02T00:15:06.222339Z","end":"2026-07-02T00:15:06.402304Z","steps":["trace[1199317588] 'process raft request' (duration: 179.411366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:06.402623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.86224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-07-02T00:15:06.402724Z","caller":"traceutil/trace.go:171","msg":"trace[125567943] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:608; }","duration":"177.010923ms","start":"2026-07-02T00:15:06.225685Z","end":"2026-07-02T00:15:06.402696Z","steps":["trace[125567943] 'agreement among raft nodes before linearized reading' (duration: 176.672414ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:06.419062Z","caller":"traceutil/trace.go:171","msg":"trace[1862342826] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"172.271089ms","start":"2026-07-02T00:15:06.246736Z","end":"2026-07-02T00:15:06.419007Z","steps":["trace[1862342826] 'process raft request' (duration: 171.217631ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:08.315817Z","caller":"traceutil/trace.go:171","msg":"trace[2074929784] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"179.637813ms","start":"2026-07-02T00:15:08.136119Z","end":"2026-07-02T00:15:08.315757Z","steps":["trace[2074929784] 'process raft request' (duration: 179.291664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.193446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.050382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:09.193551Z","caller":"traceutil/trace.go:171","msg":"trace[1535891723] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:620; }","duration":"477.279087ms","start":"2026-07-02T00:15:08.716245Z","end":"2026-07-02T00:15:09.193524Z","steps":["trace[1535891723] 'range keys from in-memory index tree' (duration: 476.930248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.193602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:08.71622Z","time spent":"477.372769ms","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:15:09.306441Z","caller":"traceutil/trace.go:171","msg":"trace[1800590161] linearizableReadLoop","detail":"{readStateIndex:650; appliedIndex:649; }","duration":"111.715013ms","start":"2026-07-02T00:15:09.194691Z","end":"2026-07-02T00:15:09.306406Z","steps":["trace[1800590161] 'read index received' (duration: 110.779589ms)","trace[1800590161] 'applied index is now lower than readState.Index' (duration: 933.354µs)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:09.306619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.898238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:09.306661Z","caller":"traceutil/trace.go:171","msg":"trace[1222160369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"111.965519ms","start":"2026-07-02T00:15:09.194684Z","end":"2026-07-02T00:15:09.306649Z","steps":["trace[1222160369] 'agreement among raft nodes before linearized reading' (duration: 111.849076ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:09.306638Z","caller":"traceutil/trace.go:171","msg":"trace[1903660527] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"515.209968ms","start":"2026-07-02T00:15:08.791391Z","end":"2026-07-02T00:15:09.306601Z","steps":["trace[1903660527] 'process raft request' (duration: 514.340036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.306822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:08.791346Z","time spent":"515.368442ms","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:09.831694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.315656ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:09.831939Z","caller":"traceutil/trace.go:171","msg":"trace[289897531] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"506.887385ms","start":"2026-07-02T00:15:09.324998Z","end":"2026-07-02T00:15:09.831885Z","steps":["trace[289897531] 'process raft request' (duration: 69.277322ms)","trace[289897531] 'compare' (duration: 437.057619ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:09.832007Z","caller":"traceutil/trace.go:171","msg":"trace[1309696181] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:651; }","duration":"450.254458ms","start":"2026-07-02T00:15:09.381711Z","end":"2026-07-02T00:15:09.831966Z","steps":["trace[1309696181] 'read index received' (duration: 12.572013ms)","trace[1309696181] 'applied index is now lower than readState.Index' (duration: 437.677985ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:09.832085Z","caller":"traceutil/trace.go:171","msg":"trace[1057651120] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"465.131261ms","start":"2026-07-02T00:15:09.366929Z","end":"2026-07-02T00:15:09.83206Z","steps":["trace[1057651120] 'process raft request' (duration: 464.880474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.832183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:09.324983Z","time spent":"507.042999ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:09.832222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:09.366889Z","time spent":"465.248954ms","remote":"127.0.0.1:42476","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:09.8323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.453589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:09.832332Z","caller":"traceutil/trace.go:171","msg":"trace[424081462] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"115.528341ms","start":"2026-07-02T00:15:09.71679Z","end":"2026-07-02T00:15:09.832319Z","steps":["trace[424081462] 'agreement among raft nodes before linearized reading' (duration: 115.483349ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.83224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.737563ms","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-02T00:15:09.832426Z","caller":"traceutil/trace.go:171","msg":"trace[1757120132] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"508.934108ms","start":"2026-07-02T00:15:09.323467Z","end":"2026-07-02T00:15:09.832401Z","steps":["trace[1757120132] 'agreement among raft nodes before linearized reading' (duration: 508.676291ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.832645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:09.32346Z","time spent":"509.010709ms","remote":"127.0.0.1:42530","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-07-02T00:15:09.832607Z","caller":"traceutil/trace.go:171","msg":"trace[1865606250] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"445.579638ms","start":"2026-07-02T00:15:09.386957Z","end":"2026-07-02T00:15:09.832536Z","steps":["trace[1865606250] 'process raft request' (duration: 444.927401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:09.832865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:09.386925Z","time spent":"445.790344ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:10.492651Z","caller":"traceutil/trace.go:171","msg":"trace[240321976] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"441.578803ms","start":"2026-07-02T00:15:10.051046Z","end":"2026-07-02T00:15:10.492624Z","steps":["trace[240321976] 'process raft request' (duration: 441.433979ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:10.492825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:10.051025Z","time spent":"441.718027ms","remote":"127.0.0.1:42470","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-02T00:15:11.218216Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312419896777541,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:15:11.677405Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.182839965s","expected-duration":"1s"} {"level":"info","ts":"2026-07-02T00:15:11.685979Z","caller":"traceutil/trace.go:171","msg":"trace[963463053] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"968.692607ms","start":"2026-07-02T00:15:10.717228Z","end":"2026-07-02T00:15:11.685921Z","steps":["trace[963463053] 'read index received' (duration: 960.340915ms)","trace[963463053] 'applied index is now lower than readState.Index' (duration: 8.350812ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:11.686037Z","caller":"traceutil/trace.go:171","msg":"trace[364648751] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.408364211s","start":"2026-07-02T00:15:10.277636Z","end":"2026-07-02T00:15:11.686Z","steps":["trace[364648751] 'process raft request' (duration: 1.402267826s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:11.686166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"968.904412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-02T00:15:11.686251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:10.277606Z","time spent":"1.408535035s","remote":"127.0.0.1:42394","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:11.686295Z","caller":"traceutil/trace.go:171","msg":"trace[195226108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"969.074556ms","start":"2026-07-02T00:15:10.717183Z","end":"2026-07-02T00:15:11.686258Z","steps":["trace[195226108] 'agreement among raft nodes before linearized reading' (duration: 968.875741ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:11.686364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:10.71716Z","time spent":"969.189289ms","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-02T00:15:11.686447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.165038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-02T00:15:11.686512Z","caller":"traceutil/trace.go:171","msg":"trace[912673799] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"300.400354ms","start":"2026-07-02T00:15:11.386103Z","end":"2026-07-02T00:15:11.686503Z","steps":["trace[912673799] 'agreement among raft nodes before linearized reading' (duration: 300.045225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:11.686561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.386082Z","time spent":"300.472667ms","remote":"127.0.0.1:42530","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-02T00:15:12.667316Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"747.902739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-02T00:15:12.667533Z","caller":"traceutil/trace.go:171","msg":"trace[581814147] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"977.591251ms","start":"2026-07-02T00:15:11.689915Z","end":"2026-07-02T00:15:12.667506Z","steps":["trace[581814147] 'process raft request' (duration: 229.380984ms)","trace[581814147] 'compare' (duration: 747.745016ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:12.667606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.689905Z","time spent":"977.669273ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:12.694781Z","caller":"traceutil/trace.go:171","msg":"trace[847247565] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:661; }","duration":"854.960458ms","start":"2026-07-02T00:15:11.839789Z","end":"2026-07-02T00:15:12.69475Z","steps":["trace[847247565] 'read index received' (duration: 79.522816ms)","trace[847247565] 'applied index is now lower than readState.Index' (duration: 775.436052ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:15:12.695184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.364398ms","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-02T00:15:12.695172Z","caller":"traceutil/trace.go:171","msg":"trace[1961331897] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"950.248293ms","start":"2026-07-02T00:15:11.744864Z","end":"2026-07-02T00:15:12.695112Z","steps":["trace[1961331897] 'process raft request' (duration: 949.766891ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:15:12.695233Z","caller":"traceutil/trace.go:171","msg":"trace[1139322692] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:634; }","duration":"855.505352ms","start":"2026-07-02T00:15:11.839715Z","end":"2026-07-02T00:15:12.69522Z","steps":["trace[1139322692] 'agreement among raft nodes before linearized reading' (duration: 855.286516ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:12.69528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.839685Z","time spent":"855.582754ms","remote":"127.0.0.1:42530","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-02T00:15:12.695559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.744838Z","time spent":"950.430828ms","remote":"127.0.0.1:42606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-02T00:15:12.738749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.721952ms","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-02T00:15:12.739101Z","caller":"traceutil/trace.go:171","msg":"trace[1846288869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"894.117612ms","start":"2026-07-02T00:15:11.844952Z","end":"2026-07-02T00:15:12.73907Z","steps":["trace[1846288869] 'agreement among raft nodes before linearized reading' (duration: 893.677011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:12.739318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.84494Z","time spent":"894.356318ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-02T00:15:12.739761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"899.568021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-02T00:15:12.739868Z","caller":"traceutil/trace.go:171","msg":"trace[1213096288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"899.692534ms","start":"2026-07-02T00:15:11.84015Z","end":"2026-07-02T00:15:12.739843Z","steps":["trace[1213096288] 'agreement among raft nodes before linearized reading' (duration: 898.692308ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:12.739944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:11.840114Z","time spent":"899.813767ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-02T00:15:14.579411Z","caller":"traceutil/trace.go:171","msg":"trace[1997121883] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"280.045701ms","start":"2026-07-02T00:15:14.299307Z","end":"2026-07-02T00:15:14.579353Z","steps":["trace[1997121883] 'process raft request' (duration: 275.98209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:28.672995Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232312419896777887,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-02T00:15:28.804664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.088046657s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-02T00:15:28.804734Z","caller":"traceutil/trace.go:171","msg":"trace[414028095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"1.08816319s","start":"2026-07-02T00:15:27.716556Z","end":"2026-07-02T00:15:28.804719Z","steps":["trace[414028095] 'range keys from in-memory index tree' (duration: 1.087967526s)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:28.80476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:27.716536Z","time spent":"1.088219342s","remote":"127.0.0.1:42296","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-02T00:15:28.812157Z","caller":"traceutil/trace.go:171","msg":"trace[696007954] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"639.831754ms","start":"2026-07-02T00:15:28.172295Z","end":"2026-07-02T00:15:28.812126Z","steps":["trace[696007954] 'read index received' (duration: 639.576768ms)","trace[696007954] 'applied index is now lower than readState.Index' (duration: 253.946µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:28.812318Z","caller":"traceutil/trace.go:171","msg":"trace[837464684] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"655.170941ms","start":"2026-07-02T00:15:28.157084Z","end":"2026-07-02T00:15:28.812255Z","steps":["trace[837464684] 'process raft request' (duration: 654.888515ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:28.812446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"640.12938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T00:15:28.812547Z","caller":"traceutil/trace.go:171","msg":"trace[128380700] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:807; }","duration":"640.204512ms","start":"2026-07-02T00:15:28.172284Z","end":"2026-07-02T00:15:28.812488Z","steps":["trace[128380700] 'agreement among raft nodes before linearized reading' (duration: 639.990537ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:15:28.812603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:28.172195Z","time spent":"640.392836ms","remote":"127.0.0.1:42530","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-02T00:15:28.812701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-02T00:15:28.157058Z","time spent":"655.416216ms","remote":"127.0.0.1:42504","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-02T00:15:57.15148Z","caller":"traceutil/trace.go:171","msg":"trace[1754510776] transaction","detail":"{read_only:false; response_revision:1030; number_of_response:1; }","duration":"110.771103ms","start":"2026-07-02T00:15:57.040675Z","end":"2026-07-02T00:15:57.151446Z","steps":["trace[1754510776] 'process raft request' (duration: 40.613879ms)","trace[1754510776] 'compare' (duration: 69.93941ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:15:57.151812Z","caller":"traceutil/trace.go:171","msg":"trace[1289962822] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"110.950636ms","start":"2026-07-02T00:15:57.040818Z","end":"2026-07-02T00:15:57.151768Z","steps":["trace[1289962822] 'process raft request' (duration: 110.577519ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:16:05.274621Z","caller":"traceutil/trace.go:171","msg":"trace[717907050] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"169.89704ms","start":"2026-07-02T00:16:05.104703Z","end":"2026-07-02T00:16:05.2746Z","steps":["trace[717907050] 'process raft request' (duration: 141.471697ms)","trace[717907050] 'compare' (duration: 28.196719ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:16:10.38214Z","caller":"traceutil/trace.go:171","msg":"trace[1610858289] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"106.949168ms","start":"2026-07-02T00:16:10.275168Z","end":"2026-07-02T00:16:10.382117Z","steps":["trace[1610858289] 'process raft request' (duration: 106.800015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:16:32.065207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.368681ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-02T00:16:33.787318Z","caller":"traceutil/trace.go:171","msg":"trace[1275577957] transaction","detail":"{read_only:false; response_revision:1155; number_of_response:1; }","duration":"150.800317ms","start":"2026-07-02T00:16:33.636495Z","end":"2026-07-02T00:16:33.787296Z","steps":["trace[1275577957] 'process raft request' (duration: 133.014445ms)","trace[1275577957] 'compare' (duration: 17.474227ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:16:39.377315Z","caller":"traceutil/trace.go:171","msg":"trace[153307512] transaction","detail":"{read_only:false; response_revision:1171; number_of_response:1; }","duration":"130.256541ms","start":"2026-07-02T00:16:39.247032Z","end":"2026-07-02T00:16:39.377288Z","steps":["trace[153307512] 'process raft request' (duration: 130.115029ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:16:39.790972Z","caller":"traceutil/trace.go:171","msg":"trace[1523966083] linearizableReadLoop","detail":"{readStateIndex:1223; appliedIndex:1222; }","duration":"156.846966ms","start":"2026-07-02T00:16:39.634068Z","end":"2026-07-02T00:16:39.790915Z","steps":["trace[1523966083] 'read index received' (duration: 156.705293ms)","trace[1523966083] 'applied index is now lower than readState.Index' (duration: 141.033µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:16:39.791056Z","caller":"traceutil/trace.go:171","msg":"trace[778036330] transaction","detail":"{read_only:false; response_revision:1172; number_of_response:1; }","duration":"157.878563ms","start":"2026-07-02T00:16:39.63317Z","end":"2026-07-02T00:16:39.791048Z","steps":["trace[778036330] 'process raft request' (duration: 157.652179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:16:39.791215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.13303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-02T00:16:39.791243Z","caller":"traceutil/trace.go:171","msg":"trace[768606251] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1172; }","duration":"157.195102ms","start":"2026-07-02T00:16:39.63404Z","end":"2026-07-02T00:16:39.791236Z","steps":["trace[768606251] 'agreement among raft nodes before linearized reading' (duration: 157.09054ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:16:51.672355Z","caller":"traceutil/trace.go:171","msg":"trace[298520490] transaction","detail":"{read_only:false; response_revision:1210; number_of_response:1; }","duration":"200.924795ms","start":"2026-07-02T00:16:51.471399Z","end":"2026-07-02T00:16:51.672324Z","steps":["trace[298520490] 'process raft request' (duration: 200.746122ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:10.149546Z","caller":"traceutil/trace.go:171","msg":"trace[1837349007] linearizableReadLoop","detail":"{readStateIndex:1362; appliedIndex:1361; }","duration":"123.272527ms","start":"2026-07-02T00:17:10.026249Z","end":"2026-07-02T00:17:10.149522Z","steps":["trace[1837349007] 'read index received' (duration: 102.771556ms)","trace[1837349007] 'applied index is now lower than readState.Index' (duration: 20.499801ms)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:10.149736Z","caller":"traceutil/trace.go:171","msg":"trace[985365745] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"125.065315ms","start":"2026-07-02T00:17:10.024641Z","end":"2026-07-02T00:17:10.149616Z","steps":["trace[985365745] 'process raft request' (duration: 104.471533ms)","trace[985365745] 'compare' (duration: 20.293447ms)"],"step_count":2} {"level":"warn","ts":"2026-07-02T00:17:10.149896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.597332ms","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-02T00:17:10.150025Z","caller":"traceutil/trace.go:171","msg":"trace[1327753590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"123.782296ms","start":"2026-07-02T00:17:10.026218Z","end":"2026-07-02T00:17:10.150001Z","steps":["trace[1327753590] 'agreement among raft nodes before linearized reading' (duration: 123.43545ms)"],"step_count":1} {"level":"info","ts":"2026-07-02T00:17:12.285342Z","caller":"traceutil/trace.go:171","msg":"trace[709719103] linearizableReadLoop","detail":"{readStateIndex:1370; appliedIndex:1369; }","duration":"273.317137ms","start":"2026-07-02T00:17:12.012002Z","end":"2026-07-02T00:17:12.285319Z","steps":["trace[709719103] 'read index received' (duration: 273.097494ms)","trace[709719103] 'applied index is now lower than readState.Index' (duration: 218.703µs)"],"step_count":2} {"level":"info","ts":"2026-07-02T00:17:12.285452Z","caller":"traceutil/trace.go:171","msg":"trace[1962557537] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"280.841045ms","start":"2026-07-02T00:17:12.0046Z","end":"2026-07-02T00:17:12.285441Z","steps":["trace[1962557537] 'process raft request' (duration: 280.574481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:12.285691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.087317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-07-02T00:17:12.285789Z","caller":"traceutil/trace.go:171","msg":"trace[1165465567] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1310; }","duration":"132.23363ms","start":"2026-07-02T00:17:12.153531Z","end":"2026-07-02T00:17:12.285765Z","steps":["trace[1165465567] 'agreement among raft nodes before linearized reading' (duration: 131.965286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-02T00:17:12.285691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.658842ms","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-02T00:17:12.28594Z","caller":"traceutil/trace.go:171","msg":"trace[749459476] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1310; }","duration":"273.945667ms","start":"2026-07-02T00:17:12.011976Z","end":"2026-07-02T00:17:12.285922Z","steps":["trace[749459476] 'agreement among raft nodes before linearized reading' (duration: 273.585481ms)"],"step_count":1}